-- Logs begin at Thu 2025-10-30 12:23:09 CST, end at Thu 2025-10-30 12:28:24 CST. -- Oct 30 12:27:00 volumio volumio[955]: 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 Oct 30 12:27:00 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:00 volumio volumio[955]: info: CorePlayQueue::getTrack 51 Oct 30 12:27:03 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::play index 50 Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:03 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:03 volumio volumio[955]: info: CorePlayQueue::getTrack 51 Oct 30 12:27:03 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:03 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:03 volumio volumio[955]: info: CorePlayQueue::getTrack 51 Oct 30 12:27:03 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:03 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:03 volumio volumio[955]: info: sendMpdCommand stop took 32 milliseconds Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:03 volumio volumio[955]: info: CorePlayQueue::getTrack 50 Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:03 volumio volumio[955]: info: CorePlayQueue::getTrack 50 Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/DJ Pk - The Sexy Girl Ft Easy Tech.mp3 Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:03 volumio volumio[955]: info: Oct 30 12:27:03 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:03 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:03 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:03 volumio volumio[955]: info: Oct 30 12:27:03 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:03 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:03 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:27:03 volumio volumio[955]: info: sendMpdCommand clear took 2 milliseconds Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/DJ Pk - The Sexy Girl Ft Easy Tech.mp3" Oct 30 12:27:03 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:03 volumio volumio[955]: info: Oct 30 12:27:03 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:03 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:03 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:27:03 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:03 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/DJ Pk - The Sexy Girl Ft Easy Tech.mp3" took 1 milliseconds Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:03 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 12:27:03 volumio volumio[955]: info: ------------------------------ 6ms Oct 30 12:27:03 volumio volumio[955]: info: Oct 30 12:27:03 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:03 volumio volumio[955]: info: ------------------------------ 3ms Oct 30 12:27:03 volumio volumio[955]: info: sendMpdCommand play took 3 milliseconds Oct 30 12:27:03 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:03 volumio volumio[955]: info: Oct 30 12:27:03 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:03 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:03 volumio volumio[955]: info: sendMpdCommand status took 8 milliseconds Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:03 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:27:03 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:03 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:03 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:03 volumio volumio[955]: info: CorePlayQueue::getTrack 50 Oct 30 12:27:03 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":318,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Sexy Girl Ft EasyTech(Ô½ÄÏDjPk Rmx)-Å®ElectroHo","artist":"www.djkk.com","album":"DJàËàËÍø","uri":"USB/2E38-3CE5/DJ Pk - The Sexy Girl Ft Easy Tech.mp3","trackType":"mp3"} Oct 30 12:27:03 volumio volumio[955]: verbose: CURRENT POSITION 50 Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:03 volumio volumio[955]: info: ------------------------------ 12ms Oct 30 12:27:03 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:03 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:03 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:03 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:03 volumio volumio[955]: info: CorePlayQueue::getTrack 50 Oct 30 12:27:03 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":318,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Sexy Girl Ft EasyTech(Ô½ÄÏDjPk Rmx)-Å®ElectroHo","artist":"www.djkk.com","album":"DJàËàËÍø","uri":"USB/2E38-3CE5/DJ Pk - The Sexy Girl Ft Easy Tech.mp3","trackType":"mp3"} Oct 30 12:27:03 volumio volumio[955]: verbose: CURRENT POSITION 50 Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:03 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:03 volumio volumio[955]: info: CorePlayQueue::getTrack 50 Oct 30 12:27:03 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:03 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:03 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:03 volumio volumio[955]: info: CorePlayQueue::getTrack 50 Oct 30 12:27:03 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:03 volumio volumio[955]: info: ------------------------------ 15ms Oct 30 12:27:04 volumio volumio[955]: 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 Oct 30 12:27:04 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:04 volumio volumio[955]: info: CorePlayQueue::getTrack 50 Oct 30 12:27:06 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::play index 49 Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:06 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:06 volumio volumio[955]: info: CorePlayQueue::getTrack 50 Oct 30 12:27:06 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:06 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:06 volumio volumio[955]: info: CorePlayQueue::getTrack 50 Oct 30 12:27:06 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:06 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:06 volumio volumio[955]: info: Oct 30 12:27:06 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:06 volumio volumio[955]: info: sendMpdCommand stop took 27 milliseconds Oct 30 12:27:06 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:06 volumio volumio[955]: info: CorePlayQueue::getTrack 49 Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:06 volumio volumio[955]: info: CorePlayQueue::getTrack 49 Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/DJ - where have you gong 听电音.mp3 Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:06 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:27:06 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:06 volumio volumio[955]: info: Oct 30 12:27:06 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:06 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:06 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:06 volumio volumio[955]: info: sendMpdCommand clear took 2 milliseconds Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/DJ - where have you gong 听电音.mp3" Oct 30 12:27:06 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:06 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:06 volumio volumio[955]: info: CorePlayQueue::getTrack 49 Oct 30 12:27:06 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:06 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:06 volumio volumio[955]: info: CorePlayQueue::getTrack 49 Oct 30 12:27:06 volumio volumio[955]: 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":"The Sexy Girl Ft EasyTech(Ô½ÄÏDjPk Rmx)-Å®ElectroHo","artist":"www.djkk.com","album":"DJàËàËÍø","uri":"USB/2E38-3CE5/DJ Pk - The Sexy Girl Ft Easy Tech.mp3","trackType":"mp3"} Oct 30 12:27:06 volumio volumio[955]: verbose: CURRENT POSITION 49 Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::syncState stateService stop Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:06 volumio volumio[955]: info: CorePlayQueue::getTrack 49 Oct 30 12:27:06 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:06 volumio volumio[955]: info: No code Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:06 volumio volumio[955]: info: CorePlayQueue::getTrack 49 Oct 30 12:27:06 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:06 volumio volumio[955]: info: ------------------------------ 21ms Oct 30 12:27:06 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:06 volumio volumio[955]: info: Oct 30 12:27:06 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:06 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:06 volumio volumio[955]: info: ------------------------------ 21ms Oct 30 12:27:06 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/DJ - where have you gong 听电音.mp3" took 20 milliseconds Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:06 volumio volumio[955]: info: ------------------------------ 8ms Oct 30 12:27:06 volumio volumio[955]: info: Oct 30 12:27:06 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:06 volumio volumio[955]: info: sendMpdCommand play took 14 milliseconds Oct 30 12:27:06 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:06 volumio volumio[955]: info: Oct 30 12:27:06 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:06 volumio volumio[955]: info: sendMpdCommand status took 9 milliseconds Oct 30 12:27:06 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:06 volumio volumio[955]: info: sendMpdCommand status took 3 milliseconds Oct 30 12:27:06 volumio volumio[955]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:06 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:06 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:06 volumio volumio[955]: info: CorePlayQueue::getTrack 49 Oct 30 12:27:06 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":389,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"surisan - where have you gone - Ô½ÄÏ¹Ä Èý²»ÁÁ","artist":"www.djkk.com","album":"DJàËàËÍø","uri":"USB/2E38-3CE5/DJ - where have you gong 听电音.mp3","trackType":"mp3"} Oct 30 12:27:06 volumio volumio[955]: verbose: CURRENT POSITION 49 Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:06 volumio volumio[955]: info: ------------------------------ 18ms Oct 30 12:27:06 volumio volumio[955]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 30 12:27:06 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:06 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:06 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:06 volumio volumio[955]: info: CorePlayQueue::getTrack 49 Oct 30 12:27:06 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":389,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"surisan - where have you gone - Ô½ÄÏ¹Ä Èý²»ÁÁ","artist":"www.djkk.com","album":"DJàËàËÍø","uri":"USB/2E38-3CE5/DJ - where have you gong 听电音.mp3","trackType":"mp3"} Oct 30 12:27:06 volumio volumio[955]: verbose: CURRENT POSITION 49 Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:06 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:06 volumio volumio[955]: info: CorePlayQueue::getTrack 49 Oct 30 12:27:06 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:06 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:06 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:06 volumio volumio[955]: info: CorePlayQueue::getTrack 49 Oct 30 12:27:06 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:06 volumio volumio[955]: info: ------------------------------ 25ms Oct 30 12:27:07 volumio volumio[955]: 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 Oct 30 12:27:07 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:07 volumio volumio[955]: info: CorePlayQueue::getTrack 49 Oct 30 12:27:08 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::play index 48 Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:08 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:08 volumio volumio[955]: info: CorePlayQueue::getTrack 49 Oct 30 12:27:08 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:08 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:08 volumio volumio[955]: info: CorePlayQueue::getTrack 49 Oct 30 12:27:08 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:08 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:08 volumio volumio[955]: info: Oct 30 12:27:08 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:08 volumio volumio[955]: info: sendMpdCommand stop took 32 milliseconds Oct 30 12:27:08 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:08 volumio volumio[955]: info: CorePlayQueue::getTrack 48 Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:08 volumio volumio[955]: info: CorePlayQueue::getTrack 48 Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/DJ - Progressive House单曲.mp3 Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:08 volumio volumio[955]: info: sendMpdCommand status took 3 milliseconds Oct 30 12:27:08 volumio volumio[955]: info: sendMpdCommand stop took 2 milliseconds Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:08 volumio volumio[955]: info: Oct 30 12:27:08 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:08 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:08 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:08 volumio volumio[955]: info: sendMpdCommand clear took 1 milliseconds Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/DJ - Progressive House单曲.mp3" Oct 30 12:27:08 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:08 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:08 volumio volumio[955]: info: CorePlayQueue::getTrack 48 Oct 30 12:27:08 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:08 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:08 volumio volumio[955]: info: CorePlayQueue::getTrack 48 Oct 30 12:27:08 volumio volumio[955]: 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":"surisan - where have you gone - Ô½ÄÏ¹Ä Èý²»ÁÁ","artist":"www.djkk.com","album":"DJàËàËÍø","uri":"USB/2E38-3CE5/DJ - where have you gong 听电音.mp3","trackType":"mp3"} Oct 30 12:27:08 volumio volumio[955]: verbose: CURRENT POSITION 48 Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::syncState stateService stop Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:08 volumio volumio[955]: info: CorePlayQueue::getTrack 48 Oct 30 12:27:08 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:08 volumio volumio[955]: info: No code Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:08 volumio volumio[955]: info: CorePlayQueue::getTrack 48 Oct 30 12:27:08 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:08 volumio volumio[955]: info: ------------------------------ 17ms Oct 30 12:27:08 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:08 volumio volumio[955]: info: Oct 30 12:27:08 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:08 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:08 volumio volumio[955]: info: ------------------------------ 14ms Oct 30 12:27:08 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/DJ - Progressive House单曲.mp3" took 13 milliseconds Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:08 volumio volumio[955]: info: Oct 30 12:27:08 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:08 volumio volumio[955]: info: ------------------------------ 7ms Oct 30 12:27:08 volumio volumio[955]: info: sendMpdCommand play took 7 milliseconds Oct 30 12:27:08 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:08 volumio volumio[955]: info: Oct 30 12:27:08 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:08 volumio volumio[955]: info: sendMpdCommand status took 18 milliseconds Oct 30 12:27:08 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:08 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:08 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:27:08 volumio volumio[955]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 30 12:27:08 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:08 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:08 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:08 volumio volumio[955]: info: CorePlayQueue::getTrack 48 Oct 30 12:27:08 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":426,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Progressive HouseÍƼöµ¥Çú01","artist":"www.djkk.com","album":"DJàËàËÍø","uri":"USB/2E38-3CE5/DJ - Progressive House单曲.mp3","trackType":"mp3"} Oct 30 12:27:08 volumio volumio[955]: verbose: CURRENT POSITION 48 Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:08 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:08 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:08 volumio volumio[955]: info: CorePlayQueue::getTrack 48 Oct 30 12:27:08 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":426,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Progressive HouseÍƼöµ¥Çú01","artist":"www.djkk.com","album":"DJàËàËÍø","uri":"USB/2E38-3CE5/DJ - Progressive House单曲.mp3","trackType":"mp3"} Oct 30 12:27:08 volumio volumio[955]: verbose: CURRENT POSITION 48 Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:08 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:08 volumio volumio[955]: info: CorePlayQueue::getTrack 48 Oct 30 12:27:08 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:08 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:08 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:08 volumio volumio[955]: info: CorePlayQueue::getTrack 48 Oct 30 12:27:08 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:08 volumio volumio[955]: info: ------------------------------ 29ms Oct 30 12:27:08 volumio volumio[955]: info: ------------------------------ 15ms Oct 30 12:27:09 volumio volumio[955]: 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 Oct 30 12:27:09 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:09 volumio volumio[955]: info: CorePlayQueue::getTrack 48 Oct 30 12:27:11 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::play index 47 Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:11 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:11 volumio volumio[955]: info: CorePlayQueue::getTrack 48 Oct 30 12:27:11 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:11 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:11 volumio volumio[955]: info: CorePlayQueue::getTrack 48 Oct 30 12:27:11 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:11 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:11 volumio volumio[955]: info: Oct 30 12:27:11 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:11 volumio volumio[955]: info: sendMpdCommand stop took 23 milliseconds Oct 30 12:27:11 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:11 volumio volumio[955]: info: CorePlayQueue::getTrack 47 Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:11 volumio volumio[955]: info: CorePlayQueue::getTrack 47 Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Ozuna - Dile Que Tu Me Quieres (Remix).mp3 Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:11 volumio volumio[955]: info: sendMpdCommand status took 13 milliseconds Oct 30 12:27:11 volumio volumio[955]: info: sendMpdCommand stop took 13 milliseconds Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:11 volumio volumio[955]: info: Oct 30 12:27:11 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:11 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:11 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:27:11 volumio volumio[955]: info: sendMpdCommand clear took 2 milliseconds Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Ozuna - Dile Que Tu Me Quieres (Remix).mp3" Oct 30 12:27:11 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:11 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:11 volumio volumio[955]: info: CorePlayQueue::getTrack 47 Oct 30 12:27:11 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:11 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:11 volumio volumio[955]: info: CorePlayQueue::getTrack 47 Oct 30 12:27:11 volumio volumio[955]: 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":"Progressive HouseÍƼöµ¥Çú01","artist":"www.djkk.com","album":"DJàËàËÍø","uri":"USB/2E38-3CE5/DJ - Progressive House单曲.mp3","trackType":"mp3"} Oct 30 12:27:11 volumio volumio[955]: verbose: CURRENT POSITION 47 Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::syncState stateService stop Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:11 volumio volumio[955]: info: CorePlayQueue::getTrack 47 Oct 30 12:27:11 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:11 volumio volumio[955]: info: No code Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:11 volumio volumio[955]: info: CorePlayQueue::getTrack 47 Oct 30 12:27:11 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:11 volumio volumio[955]: info: ------------------------------ 28ms Oct 30 12:27:11 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:11 volumio volumio[955]: info: Oct 30 12:27:11 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:11 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:11 volumio volumio[955]: info: ------------------------------ 13ms Oct 30 12:27:11 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Ozuna - Dile Que Tu Me Quieres (Remix).mp3" took 12 milliseconds Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:11 volumio volumio[955]: info: Oct 30 12:27:11 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:11 volumio volumio[955]: info: ------------------------------ 11ms Oct 30 12:27:11 volumio volumio[955]: info: sendMpdCommand play took 10 milliseconds Oct 30 12:27:11 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:11 volumio volumio[955]: info: Oct 30 12:27:11 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:11 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:11 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:11 volumio volumio[955]: info: sendMpdCommand status took 8 milliseconds Oct 30 12:27:11 volumio volumio[955]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:11 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:11 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:11 volumio volumio[955]: info: CorePlayQueue::getTrack 47 Oct 30 12:27:11 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dile Que Tu Me Quieres (Remix)","artist":"Ozuna","album":"Dile Que Tu Me Quieres (Remix)","uri":"USB/2E38-3CE5/Ozuna - Dile Que Tu Me Quieres (Remix).mp3","trackType":"mp3"} Oct 30 12:27:11 volumio volumio[955]: verbose: CURRENT POSITION 47 Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:11 volumio volumio[955]: info: ------------------------------ 12ms Oct 30 12:27:11 volumio volumio[955]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 12:27:11 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:11 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:11 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:11 volumio volumio[955]: info: CorePlayQueue::getTrack 47 Oct 30 12:27:11 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dile Que Tu Me Quieres (Remix)","artist":"Ozuna","album":"Dile Que Tu Me Quieres (Remix)","uri":"USB/2E38-3CE5/Ozuna - Dile Que Tu Me Quieres (Remix).mp3","trackType":"mp3"} Oct 30 12:27:11 volumio volumio[955]: verbose: CURRENT POSITION 47 Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:11 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:11 volumio volumio[955]: info: CorePlayQueue::getTrack 47 Oct 30 12:27:11 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:11 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:11 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:11 volumio volumio[955]: info: CorePlayQueue::getTrack 47 Oct 30 12:27:11 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:11 volumio volumio[955]: info: ------------------------------ 23ms Oct 30 12:27:12 volumio volumio[955]: 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 Oct 30 12:27:12 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:12 volumio volumio[955]: info: CorePlayQueue::getTrack 47 Oct 30 12:27:13 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::play index 46 Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:13 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:13 volumio volumio[955]: info: CorePlayQueue::getTrack 47 Oct 30 12:27:13 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:13 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:13 volumio volumio[955]: info: CorePlayQueue::getTrack 47 Oct 30 12:27:13 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:13 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:13 volumio volumio[955]: info: Oct 30 12:27:13 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:13 volumio volumio[955]: info: sendMpdCommand stop took 26 milliseconds Oct 30 12:27:13 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:13 volumio volumio[955]: info: CorePlayQueue::getTrack 46 Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:13 volumio volumio[955]: info: CorePlayQueue::getTrack 46 Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Luis Fonsi - Despacito.mp3 Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:13 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:27:13 volumio volumio[955]: info: sendMpdCommand stop took 0 milliseconds Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:13 volumio volumio[955]: info: Oct 30 12:27:13 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:13 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:13 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:13 volumio volumio[955]: info: sendMpdCommand clear took 1 milliseconds Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Luis Fonsi - Despacito.mp3" Oct 30 12:27:13 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:13 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:13 volumio volumio[955]: info: CorePlayQueue::getTrack 46 Oct 30 12:27:13 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:13 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:13 volumio volumio[955]: info: CorePlayQueue::getTrack 46 Oct 30 12:27:13 volumio volumio[955]: 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":"Dile Que Tu Me Quieres (Remix)","artist":"Ozuna","album":"Dile Que Tu Me Quieres (Remix)","uri":"USB/2E38-3CE5/Ozuna - Dile Que Tu Me Quieres (Remix).mp3","trackType":"mp3"} Oct 30 12:27:13 volumio volumio[955]: verbose: CURRENT POSITION 46 Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::syncState stateService stop Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:13 volumio volumio[955]: info: CorePlayQueue::getTrack 46 Oct 30 12:27:13 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:13 volumio volumio[955]: info: No code Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:13 volumio volumio[955]: info: CorePlayQueue::getTrack 46 Oct 30 12:27:13 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:13 volumio volumio[955]: info: ------------------------------ 15ms Oct 30 12:27:13 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:13 volumio volumio[955]: info: Oct 30 12:27:13 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:13 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:13 volumio volumio[955]: info: ------------------------------ 12ms Oct 30 12:27:13 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Luis Fonsi - Despacito.mp3" took 11 milliseconds Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:13 volumio volumio[955]: info: Oct 30 12:27:13 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:13 volumio volumio[955]: info: ------------------------------ 10ms Oct 30 12:27:13 volumio volumio[955]: info: sendMpdCommand play took 10 milliseconds Oct 30 12:27:13 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:13 volumio volumio[955]: info: Oct 30 12:27:13 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:13 volumio volumio[955]: info: sendMpdCommand status took 7 milliseconds Oct 30 12:27:13 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:13 volumio volumio[955]: info: sendMpdCommand status took 10 milliseconds Oct 30 12:27:13 volumio volumio[955]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:13 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:13 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:13 volumio volumio[955]: info: CorePlayQueue::getTrack 46 Oct 30 12:27:13 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Despacito","artist":"Luis Fonsi","album":"Despacito","uri":"USB/2E38-3CE5/Luis Fonsi - Despacito.mp3","trackType":"mp3"} Oct 30 12:27:13 volumio volumio[955]: verbose: CURRENT POSITION 46 Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:13 volumio volumio[955]: info: ------------------------------ 20ms Oct 30 12:27:13 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Luis%20Fonsi/Despacito/d8e73766-ff37-4028-9120-26fbecac75da.jpg' Oct 30 12:27:13 volumio volumio[955]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 30 12:27:13 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:13 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:13 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:13 volumio volumio[955]: info: CorePlayQueue::getTrack 46 Oct 30 12:27:13 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Despacito","artist":"Luis Fonsi","album":"Despacito","uri":"USB/2E38-3CE5/Luis Fonsi - Despacito.mp3","trackType":"mp3"} Oct 30 12:27:13 volumio volumio[955]: verbose: CURRENT POSITION 46 Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:13 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:13 volumio volumio[955]: info: CorePlayQueue::getTrack 46 Oct 30 12:27:13 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:13 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:13 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:13 volumio volumio[955]: info: CorePlayQueue::getTrack 46 Oct 30 12:27:13 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:13 volumio volumio[955]: info: ------------------------------ 36ms Oct 30 12:27:13 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Luis%20Fonsi/Despacito/d8e73766-ff37-4028-9120-26fbecac75da.jpg' Oct 30 12:27:13 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Luis%20Fonsi/Despacito/d8e73766-ff37-4028-9120-26fbecac75da.jpg' Oct 30 12:27:13 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Luis%20Fonsi/Despacito/d8e73766-ff37-4028-9120-26fbecac75da.jpg' Oct 30 12:27:14 volumio volumio[955]: 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 Oct 30 12:27:14 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:14 volumio volumio[955]: info: CorePlayQueue::getTrack 46 Oct 30 12:27:14 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Luis%20Fonsi/Despacito/d8e73766-ff37-4028-9120-26fbecac75da.jpg' Oct 30 12:27:15 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::play index 45 Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:15 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:15 volumio volumio[955]: info: CorePlayQueue::getTrack 46 Oct 30 12:27:15 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:15 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:15 volumio volumio[955]: info: CorePlayQueue::getTrack 46 Oct 30 12:27:15 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:15 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:15 volumio volumio[955]: info: Oct 30 12:27:15 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:15 volumio volumio[955]: info: sendMpdCommand stop took 19 milliseconds Oct 30 12:27:15 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:15 volumio volumio[955]: info: CorePlayQueue::getTrack 45 Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:15 volumio volumio[955]: info: CorePlayQueue::getTrack 45 Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Valentino Khan - Deep Down Low.mp3 Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:15 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:15 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:15 volumio volumio[955]: info: Oct 30 12:27:15 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:15 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:15 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:27:15 volumio volumio[955]: info: sendMpdCommand clear took 1 milliseconds Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Valentino Khan - Deep Down Low.mp3" Oct 30 12:27:15 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:15 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:15 volumio volumio[955]: info: CorePlayQueue::getTrack 45 Oct 30 12:27:15 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:15 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:15 volumio volumio[955]: info: CorePlayQueue::getTrack 45 Oct 30 12:27:15 volumio volumio[955]: 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":"Despacito","artist":"Luis Fonsi","album":"Despacito","uri":"USB/2E38-3CE5/Luis Fonsi - Despacito.mp3","trackType":"mp3"} Oct 30 12:27:15 volumio volumio[955]: verbose: CURRENT POSITION 45 Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::syncState stateService stop Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:15 volumio volumio[955]: info: CorePlayQueue::getTrack 45 Oct 30 12:27:15 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:15 volumio volumio[955]: info: No code Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:15 volumio volumio[955]: info: CorePlayQueue::getTrack 45 Oct 30 12:27:15 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:15 volumio volumio[955]: info: ------------------------------ 17ms Oct 30 12:27:15 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:15 volumio volumio[955]: info: Oct 30 12:27:15 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:15 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:15 volumio volumio[955]: info: ------------------------------ 15ms Oct 30 12:27:15 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Valentino Khan - Deep Down Low.mp3" took 14 milliseconds Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:15 volumio volumio[955]: info: Oct 30 12:27:15 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:15 volumio volumio[955]: info: ------------------------------ 14ms Oct 30 12:27:15 volumio volumio[955]: info: sendMpdCommand play took 14 milliseconds Oct 30 12:27:15 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:15 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Luis%20Fonsi/Despacito/d8e73766-ff37-4028-9120-26fbecac75da.jpg' Oct 30 12:27:15 volumio volumio[955]: info: Oct 30 12:27:15 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:15 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:15 volumio volumio[955]: info: sendMpdCommand status took 15 milliseconds Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:15 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Valentino%20Khan/Deep%20Down%20Low%20(Remixes)/51b75055-c046-421a-a665-820c7088229f.jpg' Oct 30 12:27:15 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Valentino%20Khan/Deep%20Down%20Low%20(Remixes)/51b75055-c046-421a-a665-820c7088229f.jpg' Oct 30 12:27:15 volumio volumio[955]: info: sendMpdCommand status took 10 milliseconds Oct 30 12:27:15 volumio volumio[955]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:15 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:15 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:15 volumio volumio[955]: info: CorePlayQueue::getTrack 45 Oct 30 12:27:15 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":185,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Deep Down Low","artist":"Valentino Khan","album":"Deep Down Low (Remixes)","uri":"USB/2E38-3CE5/Valentino Khan - Deep Down Low.mp3","trackType":"mp3"} Oct 30 12:27:15 volumio volumio[955]: verbose: CURRENT POSITION 45 Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:15 volumio volumio[955]: info: ------------------------------ 28ms Oct 30 12:27:15 volumio volumio[955]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 30 12:27:15 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:15 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:15 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:15 volumio volumio[955]: info: CorePlayQueue::getTrack 45 Oct 30 12:27:15 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":185,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Deep Down Low","artist":"Valentino Khan","album":"Deep Down Low (Remixes)","uri":"USB/2E38-3CE5/Valentino Khan - Deep Down Low.mp3","trackType":"mp3"} Oct 30 12:27:15 volumio volumio[955]: verbose: CURRENT POSITION 45 Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:15 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:15 volumio volumio[955]: info: CorePlayQueue::getTrack 45 Oct 30 12:27:15 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:15 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:15 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:15 volumio volumio[955]: info: CorePlayQueue::getTrack 45 Oct 30 12:27:15 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:15 volumio volumio[955]: info: ------------------------------ 34ms Oct 30 12:27:15 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Valentino%20Khan/Deep%20Down%20Low%20(Remixes)/51b75055-c046-421a-a665-820c7088229f.jpg' Oct 30 12:27:15 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Valentino%20Khan/Deep%20Down%20Low%20(Remixes)/51b75055-c046-421a-a665-820c7088229f.jpg' Oct 30 12:27:16 volumio volumio[955]: 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 Oct 30 12:27:16 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:16 volumio volumio[955]: info: CorePlayQueue::getTrack 45 Oct 30 12:27:16 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Valentino%20Khan/Deep%20Down%20Low%20(Remixes)/51b75055-c046-421a-a665-820c7088229f.jpg' Oct 30 12:27:23 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::play index 44 Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:23 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:23 volumio volumio[955]: info: CorePlayQueue::getTrack 45 Oct 30 12:27:23 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:23 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:23 volumio volumio[955]: info: CorePlayQueue::getTrack 45 Oct 30 12:27:23 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:23 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:23 volumio volumio[955]: info: sendMpdCommand stop took 21 milliseconds Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:23 volumio volumio[955]: info: CorePlayQueue::getTrack 44 Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:23 volumio volumio[955]: info: CorePlayQueue::getTrack 44 Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Platinum Doug - Cream (Original Mix).mp3 Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:23 volumio volumio[955]: info: Oct 30 12:27:23 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:23 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:23 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:23 volumio volumio[955]: info: Oct 30 12:27:23 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:23 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:23 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:23 volumio volumio[955]: info: sendMpdCommand clear took 1 milliseconds Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Platinum Doug - Cream (Original Mix).mp3" Oct 30 12:27:23 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:23 volumio volumio[955]: info: Oct 30 12:27:23 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:23 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:23 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:27:23 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:23 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Platinum Doug - Cream (Original Mix).mp3" took 1 milliseconds Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:23 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 12:27:23 volumio volumio[955]: info: ------------------------------ 9ms Oct 30 12:27:23 volumio volumio[955]: info: ------------------------------ 6ms Oct 30 12:27:23 volumio volumio[955]: info: sendMpdCommand play took 1 milliseconds Oct 30 12:27:23 volumio volumio[955]: info: Oct 30 12:27:23 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:23 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:23 volumio volumio[955]: info: Oct 30 12:27:23 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:23 volumio volumio[955]: info: sendMpdCommand status took 9 milliseconds Oct 30 12:27:23 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:23 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:27:23 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:23 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:23 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:23 volumio volumio[955]: info: CorePlayQueue::getTrack 44 Oct 30 12:27:23 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":327,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cream (Original Mix)","artist":"Platinum Doug","album":"Cream","uri":"USB/2E38-3CE5/Platinum Doug - Cream (Original Mix).mp3","trackType":"mp3"} Oct 30 12:27:23 volumio volumio[955]: verbose: CURRENT POSITION 44 Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:23 volumio volumio[955]: info: ------------------------------ 13ms Oct 30 12:27:23 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:27:23 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:23 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:23 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:23 volumio volumio[955]: info: CorePlayQueue::getTrack 44 Oct 30 12:27:23 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":327,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cream (Original Mix)","artist":"Platinum Doug","album":"Cream","uri":"USB/2E38-3CE5/Platinum Doug - Cream (Original Mix).mp3","trackType":"mp3"} Oct 30 12:27:23 volumio volumio[955]: verbose: CURRENT POSITION 44 Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:23 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:23 volumio volumio[955]: info: CorePlayQueue::getTrack 44 Oct 30 12:27:23 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:23 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:23 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:23 volumio volumio[955]: info: CorePlayQueue::getTrack 44 Oct 30 12:27:23 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:23 volumio volumio[955]: info: ------------------------------ 16ms Oct 30 12:27:23 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Platinum%20Doug/Cream/a20d8f85-ff11-4256-9074-46c01f74dd0f.jpg' Oct 30 12:27:23 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Platinum%20Doug/Cream/a20d8f85-ff11-4256-9074-46c01f74dd0f.jpg' Oct 30 12:27:23 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Platinum%20Doug/Cream/a20d8f85-ff11-4256-9074-46c01f74dd0f.jpg' Oct 30 12:27:23 volumio volumio[955]: 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 Oct 30 12:27:24 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:24 volumio volumio[955]: info: CorePlayQueue::getTrack 44 Oct 30 12:27:24 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Platinum%20Doug/Cream/a20d8f85-ff11-4256-9074-46c01f74dd0f.jpg' Oct 30 12:27:25 volumio volumio[955]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 30 12:27:25 volumio volumio[955]: info: Retrying Login Due to Network Error Oct 30 12:27:27 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::play index 43 Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:27 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:27 volumio volumio[955]: info: CorePlayQueue::getTrack 44 Oct 30 12:27:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:27 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:27 volumio volumio[955]: info: CorePlayQueue::getTrack 44 Oct 30 12:27:27 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:27 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:27 volumio volumio[955]: info: sendMpdCommand stop took 22 milliseconds Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:27 volumio volumio[955]: info: CorePlayQueue::getTrack 43 Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:27 volumio volumio[955]: info: CorePlayQueue::getTrack 43 Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Niels van Gogh - Pornstar.mp3 Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:27 volumio volumio[955]: info: Oct 30 12:27:27 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:27 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:27 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:27 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:27 volumio volumio[955]: info: Oct 30 12:27:27 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:27 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:27 volumio volumio[955]: info: sendMpdCommand clear took 3 milliseconds Oct 30 12:27:27 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Niels van Gogh - Pornstar.mp3" Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:27 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 12:27:27 volumio volumio[955]: info: ------------------------------ 6ms Oct 30 12:27:27 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:27 volumio volumio[955]: info: Oct 30 12:27:27 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:27 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:27 volumio volumio[955]: info: ------------------------------ 3ms Oct 30 12:27:27 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Niels van Gogh - Pornstar.mp3" took 2 milliseconds Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:27 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:27:27 volumio volumio[955]: info: sendMpdCommand play took 2 milliseconds Oct 30 12:27:27 volumio volumio[955]: info: Oct 30 12:27:27 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:27 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:27 volumio volumio[955]: info: sendMpdCommand status took 8 milliseconds Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:27 volumio volumio[955]: info: Oct 30 12:27:27 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:27 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:27 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:27 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:27 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:27 volumio volumio[955]: info: CorePlayQueue::getTrack 43 Oct 30 12:27:27 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":170,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pornstar","artist":"Niels van Gogh","album":"Club Sounds, Vol. 66","uri":"USB/2E38-3CE5/Niels van Gogh - Pornstar.mp3","trackType":"mp3"} Oct 30 12:27:27 volumio volumio[955]: verbose: CURRENT POSITION 43 Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:27 volumio volumio[955]: info: ------------------------------ 12ms Oct 30 12:27:27 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:27 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:27 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:27 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:27 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:27 volumio volumio[955]: info: CorePlayQueue::getTrack 43 Oct 30 12:27:27 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":170,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pornstar","artist":"Niels van Gogh","album":"Club Sounds, Vol. 66","uri":"USB/2E38-3CE5/Niels van Gogh - Pornstar.mp3","trackType":"mp3"} Oct 30 12:27:27 volumio volumio[955]: verbose: CURRENT POSITION 43 Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:27 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:27 volumio volumio[955]: info: CorePlayQueue::getTrack 43 Oct 30 12:27:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:27 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:27 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:27 volumio volumio[955]: info: CorePlayQueue::getTrack 43 Oct 30 12:27:27 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:27 volumio volumio[955]: info: ------------------------------ 16ms Oct 30 12:27:28 volumio volumio[955]: 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 Oct 30 12:27:28 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:28 volumio volumio[955]: info: CorePlayQueue::getTrack 43 Oct 30 12:27:30 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::play index 42 Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:30 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:30 volumio volumio[955]: info: CorePlayQueue::getTrack 43 Oct 30 12:27:30 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:30 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:30 volumio volumio[955]: info: CorePlayQueue::getTrack 43 Oct 30 12:27:30 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:30 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:30 volumio volumio[955]: info: Oct 30 12:27:30 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:30 volumio volumio[955]: info: sendMpdCommand stop took 18 milliseconds Oct 30 12:27:30 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:30 volumio volumio[955]: info: CorePlayQueue::getTrack 42 Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:30 volumio volumio[955]: info: CorePlayQueue::getTrack 42 Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Basim - Cliche Love Song.flac Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:30 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:30 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:30 volumio volumio[955]: info: Oct 30 12:27:30 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:30 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:30 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:27:30 volumio volumio[955]: info: sendMpdCommand clear took 2 milliseconds Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Basim - Cliche Love Song.flac" Oct 30 12:27:30 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:30 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:30 volumio volumio[955]: info: CorePlayQueue::getTrack 42 Oct 30 12:27:30 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:30 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:30 volumio volumio[955]: info: CorePlayQueue::getTrack 42 Oct 30 12:27:30 volumio volumio[955]: 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":"Pornstar","artist":"Niels van Gogh","album":"Club Sounds, Vol. 66","uri":"USB/2E38-3CE5/Niels van Gogh - Pornstar.mp3","trackType":"mp3"} Oct 30 12:27:30 volumio volumio[955]: verbose: CURRENT POSITION 42 Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::syncState stateService stop Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:30 volumio volumio[955]: info: CorePlayQueue::getTrack 42 Oct 30 12:27:30 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:30 volumio volumio[955]: info: No code Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:30 volumio volumio[955]: info: CorePlayQueue::getTrack 42 Oct 30 12:27:30 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:30 volumio volumio[955]: info: ------------------------------ 17ms Oct 30 12:27:30 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:30 volumio volumio[955]: info: Oct 30 12:27:30 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:30 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:30 volumio volumio[955]: info: ------------------------------ 15ms Oct 30 12:27:30 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Basim - Cliche Love Song.flac" took 14 milliseconds Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:30 volumio volumio[955]: info: Oct 30 12:27:30 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:30 volumio volumio[955]: info: ------------------------------ 14ms Oct 30 12:27:30 volumio volumio[955]: info: sendMpdCommand play took 13 milliseconds Oct 30 12:27:30 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:30 volumio volumio[955]: info: Oct 30 12:27:30 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:30 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:30 volumio volumio[955]: info: sendMpdCommand status took 7 milliseconds Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:30 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:30 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:30 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:30 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:30 volumio volumio[955]: info: CorePlayQueue::getTrack 42 Oct 30 12:27:30 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"923 Kbps","isStreaming":false,"title":"Cliche Love Song","artist":"Basim","album":"Cliche Love Song","uri":"USB/2E38-3CE5/Basim - Cliche Love Song.flac","trackType":"flac"} Oct 30 12:27:30 volumio volumio[955]: verbose: CURRENT POSITION 42 Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:30 volumio volumio[955]: info: ------------------------------ 13ms Oct 30 12:27:30 volumio volumio[955]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 30 12:27:30 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:30 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:30 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:30 volumio volumio[955]: info: CorePlayQueue::getTrack 42 Oct 30 12:27:30 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"864 Kbps","isStreaming":false,"title":"Cliche Love Song","artist":"Basim","album":"Cliche Love Song","uri":"USB/2E38-3CE5/Basim - Cliche Love Song.flac","trackType":"flac"} Oct 30 12:27:30 volumio volumio[955]: verbose: CURRENT POSITION 42 Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:30 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:30 volumio volumio[955]: info: CorePlayQueue::getTrack 42 Oct 30 12:27:30 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:30 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:30 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:30 volumio volumio[955]: info: CorePlayQueue::getTrack 42 Oct 30 12:27:30 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:30 volumio volumio[955]: info: ------------------------------ 19ms Oct 30 12:27:30 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Basim/Cliche%20Love%20Song/bf3452d1-005b-4b6e-9e9e-a5ef7bf221b1.jpg' Oct 30 12:27:30 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Basim/Cliche%20Love%20Song/bf3452d1-005b-4b6e-9e9e-a5ef7bf221b1.jpg' Oct 30 12:27:30 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Basim/Cliche%20Love%20Song/bf3452d1-005b-4b6e-9e9e-a5ef7bf221b1.jpg' Oct 30 12:27:30 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Basim/Cliche%20Love%20Song/bf3452d1-005b-4b6e-9e9e-a5ef7bf221b1.jpg' Oct 30 12:27:30 volumio volumio[955]: 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 Oct 30 12:27:30 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:30 volumio volumio[955]: info: CorePlayQueue::getTrack 42 Oct 30 12:27:30 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Basim/Cliche%20Love%20Song/bf3452d1-005b-4b6e-9e9e-a5ef7bf221b1.jpg' Oct 30 12:27:32 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::play index 41 Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:32 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:32 volumio volumio[955]: info: CorePlayQueue::getTrack 42 Oct 30 12:27:32 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:32 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:32 volumio volumio[955]: info: CorePlayQueue::getTrack 42 Oct 30 12:27:32 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:32 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:32 volumio volumio[955]: info: sendMpdCommand stop took 36 milliseconds Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:32 volumio volumio[955]: info: CorePlayQueue::getTrack 41 Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:32 volumio volumio[955]: info: CorePlayQueue::getTrack 41 Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Illy - Catch (Explicit).mp3 Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:32 volumio volumio[955]: info: Oct 30 12:27:32 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:32 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:32 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:32 volumio volumio[955]: info: Oct 30 12:27:32 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:32 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:32 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:27:32 volumio volumio[955]: info: sendMpdCommand clear took 1 milliseconds Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Illy - Catch (Explicit).mp3" Oct 30 12:27:32 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:32 volumio volumio[955]: info: Oct 30 12:27:32 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:32 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:32 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:27:32 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:32 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Illy - Catch (Explicit).mp3" took 1 milliseconds Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:32 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 12:27:32 volumio volumio[955]: info: ------------------------------ 5ms Oct 30 12:27:32 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:27:32 volumio volumio[955]: info: Oct 30 12:27:32 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:32 volumio volumio[955]: info: sendMpdCommand play took 2 milliseconds Oct 30 12:27:32 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:32 volumio volumio[955]: info: Oct 30 12:27:32 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:32 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:32 volumio volumio[955]: info: sendMpdCommand status took 8 milliseconds Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:32 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:32 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:32 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:32 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:32 volumio volumio[955]: info: CorePlayQueue::getTrack 41 Oct 30 12:27:32 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Catch 22 (Explicit)","artist":"Illy","album":"Catch 22","uri":"USB/2E38-3CE5/Illy - Catch (Explicit).mp3","trackType":"mp3"} Oct 30 12:27:32 volumio volumio[955]: verbose: CURRENT POSITION 41 Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:32 volumio volumio[955]: info: ------------------------------ 13ms Oct 30 12:27:32 volumio volumio[955]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 30 12:27:32 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:32 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:32 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:32 volumio volumio[955]: info: CorePlayQueue::getTrack 41 Oct 30 12:27:32 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Catch 22 (Explicit)","artist":"Illy","album":"Catch 22","uri":"USB/2E38-3CE5/Illy - Catch (Explicit).mp3","trackType":"mp3"} Oct 30 12:27:32 volumio volumio[955]: verbose: CURRENT POSITION 41 Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:32 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:32 volumio volumio[955]: info: CorePlayQueue::getTrack 41 Oct 30 12:27:32 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:32 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:32 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:32 volumio volumio[955]: info: CorePlayQueue::getTrack 41 Oct 30 12:27:32 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:32 volumio volumio[955]: info: ------------------------------ 19ms Oct 30 12:27:32 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Illy/Catch%2022/55ef74b0-274b-47b7-995f-3c937ccad807.jpg' Oct 30 12:27:32 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Illy/Catch%2022/55ef74b0-274b-47b7-995f-3c937ccad807.jpg' Oct 30 12:27:32 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Illy/Catch%2022/55ef74b0-274b-47b7-995f-3c937ccad807.jpg' Oct 30 12:27:33 volumio volumio[955]: 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 Oct 30 12:27:33 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:33 volumio volumio[955]: info: CorePlayQueue::getTrack 41 Oct 30 12:27:33 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Illy/Catch%2022/55ef74b0-274b-47b7-995f-3c937ccad807.jpg' Oct 30 12:27:35 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::play index 40 Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:35 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:35 volumio volumio[955]: info: CorePlayQueue::getTrack 41 Oct 30 12:27:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:35 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:35 volumio volumio[955]: info: CorePlayQueue::getTrack 41 Oct 30 12:27:35 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:35 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:35 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Illy/Catch%2022/55ef74b0-274b-47b7-995f-3c937ccad807.jpg' Oct 30 12:27:35 volumio volumio[955]: info: sendMpdCommand stop took 33 milliseconds Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:35 volumio volumio[955]: info: CorePlayQueue::getTrack 40 Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:35 volumio volumio[955]: info: CorePlayQueue::getTrack 40 Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Alok - Byob.mp3 Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:35 volumio volumio[955]: info: Oct 30 12:27:35 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:35 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:35 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:35 volumio volumio[955]: info: Oct 30 12:27:35 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:35 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:35 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:35 volumio volumio[955]: info: sendMpdCommand clear took 1 milliseconds Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Alok - Byob.mp3" Oct 30 12:27:35 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:35 volumio volumio[955]: info: Oct 30 12:27:35 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:35 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:35 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:27:35 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:35 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Alok - Byob.mp3" took 1 milliseconds Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:35 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 12:27:35 volumio volumio[955]: info: ------------------------------ 6ms Oct 30 12:27:35 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:27:35 volumio volumio[955]: info: sendMpdCommand play took 2 milliseconds Oct 30 12:27:35 volumio volumio[955]: info: Oct 30 12:27:35 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:35 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:35 volumio volumio[955]: info: Oct 30 12:27:35 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:35 volumio volumio[955]: info: sendMpdCommand status took 8 milliseconds Oct 30 12:27:35 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:35 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:27:35 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:35 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:35 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:35 volumio volumio[955]: info: CorePlayQueue::getTrack 40 Oct 30 12:27:35 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Byob","artist":"Alok","album":"Byob","uri":"USB/2E38-3CE5/Alok - Byob.mp3","trackType":"mp3"} Oct 30 12:27:35 volumio volumio[955]: verbose: CURRENT POSITION 40 Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:35 volumio volumio[955]: info: ------------------------------ 12ms Oct 30 12:27:35 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:27:35 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:35 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:35 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:35 volumio volumio[955]: info: CorePlayQueue::getTrack 40 Oct 30 12:27:35 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Byob","artist":"Alok","album":"Byob","uri":"USB/2E38-3CE5/Alok - Byob.mp3","trackType":"mp3"} Oct 30 12:27:35 volumio volumio[955]: verbose: CURRENT POSITION 40 Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:35 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:35 volumio volumio[955]: info: CorePlayQueue::getTrack 40 Oct 30 12:27:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:35 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:35 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:35 volumio volumio[955]: info: CorePlayQueue::getTrack 40 Oct 30 12:27:35 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:35 volumio volumio[955]: info: ------------------------------ 11ms Oct 30 12:27:35 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alok/Byob/03162d08-68ef-4411-8546-2ed412cff313.jpg' Oct 30 12:27:35 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alok/Byob/03162d08-68ef-4411-8546-2ed412cff313.jpg' Oct 30 12:27:35 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alok/Byob/03162d08-68ef-4411-8546-2ed412cff313.jpg' Oct 30 12:27:35 volumio volumio[955]: info: MyVolumio login type: Token Oct 30 12:27:36 volumio volumio[955]: 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 Oct 30 12:27:36 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:36 volumio volumio[955]: info: CorePlayQueue::getTrack 40 Oct 30 12:27:36 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alok/Byob/03162d08-68ef-4411-8546-2ed412cff313.jpg' Oct 30 12:27:37 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::play index 39 Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:37 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:37 volumio volumio[955]: info: CorePlayQueue::getTrack 40 Oct 30 12:27:37 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:37 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:37 volumio volumio[955]: info: CorePlayQueue::getTrack 40 Oct 30 12:27:37 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:37 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:37 volumio volumio[955]: info: sendMpdCommand stop took 25 milliseconds Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:37 volumio volumio[955]: info: CorePlayQueue::getTrack 39 Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:37 volumio volumio[955]: info: CorePlayQueue::getTrack 39 Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Alok - Byob - 副本.mp3 Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:37 volumio volumio[955]: info: Oct 30 12:27:37 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:37 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:37 volumio volumio[955]: info: sendMpdCommand stop took 2 milliseconds Oct 30 12:27:37 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:37 volumio volumio[955]: info: Oct 30 12:27:37 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:37 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:37 volumio volumio[955]: info: sendMpdCommand clear took 1 milliseconds Oct 30 12:27:37 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Alok - Byob - 副本.mp3" Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:37 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 12:27:37 volumio volumio[955]: info: ------------------------------ 7ms Oct 30 12:27:37 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:37 volumio volumio[955]: info: Oct 30 12:27:37 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:37 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:37 volumio volumio[955]: info: ------------------------------ 5ms Oct 30 12:27:37 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Alok - Byob - 副本.mp3" took 4 milliseconds Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:37 volumio volumio[955]: info: Oct 30 12:27:37 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:37 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:27:37 volumio volumio[955]: info: sendMpdCommand play took 3 milliseconds Oct 30 12:27:37 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:37 volumio volumio[955]: info: Oct 30 12:27:37 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:37 volumio volumio[955]: info: sendMpdCommand status took 8 milliseconds Oct 30 12:27:37 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:37 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:27:37 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:37 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:37 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:37 volumio volumio[955]: info: CorePlayQueue::getTrack 39 Oct 30 12:27:37 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Byob","artist":"Alok","album":"Byob","uri":"USB/2E38-3CE5/Alok - Byob - 副本.mp3","trackType":"mp3"} Oct 30 12:27:37 volumio volumio[955]: verbose: CURRENT POSITION 39 Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:37 volumio volumio[955]: info: ------------------------------ 12ms Oct 30 12:27:37 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:37 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:37 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:37 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:37 volumio volumio[955]: info: CorePlayQueue::getTrack 39 Oct 30 12:27:37 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Byob","artist":"Alok","album":"Byob","uri":"USB/2E38-3CE5/Alok - Byob - 副本.mp3","trackType":"mp3"} Oct 30 12:27:37 volumio volumio[955]: verbose: CURRENT POSITION 39 Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:37 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:37 volumio volumio[955]: info: CorePlayQueue::getTrack 39 Oct 30 12:27:37 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:37 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:37 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:37 volumio volumio[955]: info: CorePlayQueue::getTrack 39 Oct 30 12:27:37 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:37 volumio volumio[955]: info: ------------------------------ 14ms Oct 30 12:27:38 volumio volumio[955]: 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 Oct 30 12:27:38 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:38 volumio volumio[955]: info: CorePlayQueue::getTrack 39 Oct 30 12:27:38 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alok/Byob/03162d08-68ef-4411-8546-2ed412cff313.jpg' Oct 30 12:27:39 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:39 volumio volumio[955]: info: CoreStateMachine::play index 38 Oct 30 12:27:39 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:39 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:39 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:39 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:39 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:39 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:39 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:39 volumio volumio[955]: info: CorePlayQueue::getTrack 39 Oct 30 12:27:39 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:39 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:39 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:39 volumio volumio[955]: info: CorePlayQueue::getTrack 39 Oct 30 12:27:39 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:39 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:39 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:40 volumio volumio[955]: info: sendMpdCommand stop took 19 milliseconds Oct 30 12:27:40 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:40 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:40 volumio volumio[955]: info: CorePlayQueue::getTrack 38 Oct 30 12:27:40 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:40 volumio volumio[955]: info: CorePlayQueue::getTrack 38 Oct 30 12:27:40 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Tom Budin - Butterscotch (Paraxe Remix).flac Oct 30 12:27:40 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:40 volumio volumio[955]: info: Oct 30 12:27:40 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:40 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:40 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:40 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:27:40 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:40 volumio volumio[955]: info: Oct 30 12:27:40 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:40 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:40 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:40 volumio volumio[955]: info: sendMpdCommand clear took 1 milliseconds Oct 30 12:27:40 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:40 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:40 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Tom Budin - Butterscotch (Paraxe Remix).flac" Oct 30 12:27:40 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:40 volumio volumio[955]: info: Oct 30 12:27:40 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:40 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:40 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:27:40 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:40 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Tom Budin - Butterscotch (Paraxe Remix).flac" took 1 milliseconds Oct 30 12:27:40 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:40 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:40 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 12:27:40 volumio volumio[955]: info: ------------------------------ 6ms Oct 30 12:27:40 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:27:40 volumio volumio[955]: info: sendMpdCommand play took 2 milliseconds Oct 30 12:27:40 volumio volumio[955]: info: Oct 30 12:27:40 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:40 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:40 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:40 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:40 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:40 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:40 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:40 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:40 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:40 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:40 volumio volumio[955]: info: CorePlayQueue::getTrack 38 Oct 30 12:27:40 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":332,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tom Budin - Butterscotch (Paraxe Remix).flac","artist":null,"album":"Butterscotch","uri":"USB/2E38-3CE5/Tom Budin - Butterscotch (Paraxe Remix).flac","trackType":"flac"} Oct 30 12:27:40 volumio volumio[955]: verbose: CURRENT POSITION 38 Oct 30 12:27:40 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:40 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:40 volumio volumio[955]: info: ------------------------------ 6ms Oct 30 12:27:43 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::play index 37 Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:43 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:43 volumio volumio[955]: info: CorePlayQueue::getTrack 38 Oct 30 12:27:43 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:43 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:43 volumio volumio[955]: info: CorePlayQueue::getTrack 38 Oct 30 12:27:43 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:43 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:43 volumio volumio[955]: info: Oct 30 12:27:43 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:43 volumio volumio[955]: info: sendMpdCommand stop took 31 milliseconds Oct 30 12:27:43 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:43 volumio volumio[955]: info: CorePlayQueue::getTrack 37 Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:43 volumio volumio[955]: info: CorePlayQueue::getTrack 37 Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Illusionize - Roll It.flac Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:43 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:43 volumio volumio[955]: info: sendMpdCommand stop took 2 milliseconds Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:43 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:43 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:43 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:43 volumio volumio[955]: info: CorePlayQueue::getTrack 37 Oct 30 12:27:43 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:43 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:43 volumio volumio[955]: info: CorePlayQueue::getTrack 37 Oct 30 12:27:43 volumio volumio[955]: 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":"Tom Budin - Butterscotch (Paraxe Remix).flac","artist":null,"album":"Butterscotch","uri":"USB/2E38-3CE5/Tom Budin - Butterscotch (Paraxe Remix).flac","trackType":"flac"} Oct 30 12:27:43 volumio volumio[955]: verbose: CURRENT POSITION 37 Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::syncState stateService stop Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:43 volumio volumio[955]: info: CorePlayQueue::getTrack 37 Oct 30 12:27:43 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:43 volumio volumio[955]: info: No code Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:43 volumio volumio[955]: info: CorePlayQueue::getTrack 37 Oct 30 12:27:43 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:43 volumio volumio[955]: info: ------------------------------ 27ms Oct 30 12:27:43 volumio volumio[955]: info: Oct 30 12:27:43 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:43 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:43 volumio volumio[955]: info: sendMpdCommand clear took 25 milliseconds Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Illusionize - Roll It.flac" Oct 30 12:27:43 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:43 volumio volumio[955]: info: Oct 30 12:27:43 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:43 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:43 volumio volumio[955]: info: ------------------------------ 18ms Oct 30 12:27:43 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Illusionize - Roll It.flac" took 18 milliseconds Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:43 volumio volumio[955]: info: Oct 30 12:27:43 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:43 volumio volumio[955]: info: ------------------------------ 22ms Oct 30 12:27:43 volumio volumio[955]: info: sendMpdCommand play took 18 milliseconds Oct 30 12:27:43 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:43 volumio volumio[955]: info: Oct 30 12:27:43 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:43 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:43 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:43 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:43 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:43 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:43 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:43 volumio volumio[955]: info: CorePlayQueue::getTrack 37 Oct 30 12:27:43 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Illusionize - Roll It.flac","artist":null,"album":"Bunny Tiger Summer Sampler Vol. 4","uri":"USB/2E38-3CE5/Illusionize - Roll It.flac","trackType":"flac"} Oct 30 12:27:43 volumio volumio[955]: verbose: CURRENT POSITION 37 Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:43 volumio volumio[955]: info: ------------------------------ 10ms Oct 30 12:27:43 volumio volumio[955]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 30 12:27:43 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:43 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:43 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:43 volumio volumio[955]: info: CorePlayQueue::getTrack 37 Oct 30 12:27:43 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"609 Kbps","isStreaming":false,"title":"Illusionize - Roll It.flac","artist":null,"album":"Bunny Tiger Summer Sampler Vol. 4","uri":"USB/2E38-3CE5/Illusionize - Roll It.flac","trackType":"flac"} Oct 30 12:27:43 volumio volumio[955]: verbose: CURRENT POSITION 37 Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:43 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:43 volumio volumio[955]: info: CorePlayQueue::getTrack 37 Oct 30 12:27:43 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:43 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:43 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:43 volumio volumio[955]: info: CorePlayQueue::getTrack 37 Oct 30 12:27:43 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:43 volumio volumio[955]: info: ------------------------------ 21ms Oct 30 12:27:43 volumio volumio[955]: 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 Oct 30 12:27:43 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:43 volumio volumio[955]: info: CorePlayQueue::getTrack 37 Oct 30 12:27:45 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::play index 36 Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:45 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:45 volumio volumio[955]: info: CorePlayQueue::getTrack 37 Oct 30 12:27:45 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:45 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:45 volumio volumio[955]: info: CorePlayQueue::getTrack 37 Oct 30 12:27:45 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:45 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:45 volumio volumio[955]: info: Oct 30 12:27:45 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:45 volumio volumio[955]: info: sendMpdCommand stop took 19 milliseconds Oct 30 12:27:45 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:45 volumio volumio[955]: info: CorePlayQueue::getTrack 36 Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:45 volumio volumio[955]: info: CorePlayQueue::getTrack 36 Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Tiësto - Boo.flac Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:45 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:27:45 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:45 volumio volumio[955]: info: Oct 30 12:27:45 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:45 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:45 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:45 volumio volumio[955]: info: sendMpdCommand clear took 1 milliseconds Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Tiësto - Boo.flac" Oct 30 12:27:45 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:45 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:45 volumio volumio[955]: info: CorePlayQueue::getTrack 36 Oct 30 12:27:45 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:45 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:45 volumio volumio[955]: info: CorePlayQueue::getTrack 36 Oct 30 12:27:45 volumio volumio[955]: 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":"Illusionize - Roll It.flac","artist":null,"album":"Bunny Tiger Summer Sampler Vol. 4","uri":"USB/2E38-3CE5/Illusionize - Roll It.flac","trackType":"flac"} Oct 30 12:27:45 volumio volumio[955]: verbose: CURRENT POSITION 36 Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::syncState stateService stop Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:45 volumio volumio[955]: info: CorePlayQueue::getTrack 36 Oct 30 12:27:45 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:45 volumio volumio[955]: info: No code Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:45 volumio volumio[955]: info: CorePlayQueue::getTrack 36 Oct 30 12:27:45 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:45 volumio volumio[955]: info: ------------------------------ 18ms Oct 30 12:27:45 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:45 volumio volumio[955]: info: Oct 30 12:27:45 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:45 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:45 volumio volumio[955]: info: ------------------------------ 15ms Oct 30 12:27:45 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Tiësto - Boo.flac" took 15 milliseconds Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:45 volumio volumio[955]: info: Oct 30 12:27:45 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:45 volumio volumio[955]: info: ------------------------------ 14ms Oct 30 12:27:45 volumio volumio[955]: info: sendMpdCommand play took 14 milliseconds Oct 30 12:27:45 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:45 volumio volumio[955]: info: Oct 30 12:27:45 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:45 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:45 volumio volumio[955]: info: sendMpdCommand status took 10 milliseconds Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:45 volumio volumio[955]: info: sendMpdCommand status took 7 milliseconds Oct 30 12:27:45 volumio volumio[955]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:45 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:45 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:45 volumio volumio[955]: info: CorePlayQueue::getTrack 36 Oct 30 12:27:45 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":154,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"939 Kbps","isStreaming":false,"title":"Tiësto - Boo.flac","artist":null,"album":"Boom","uri":"USB/2E38-3CE5/Tiësto - Boo.flac","trackType":"flac"} Oct 30 12:27:45 volumio volumio[955]: verbose: CURRENT POSITION 36 Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:45 volumio volumio[955]: info: ------------------------------ 21ms Oct 30 12:27:45 volumio volumio[955]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 30 12:27:45 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:45 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:45 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:45 volumio volumio[955]: info: CorePlayQueue::getTrack 36 Oct 30 12:27:45 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":154,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1101 Kbps","isStreaming":false,"title":"Tiësto - Boo.flac","artist":null,"album":"Boom","uri":"USB/2E38-3CE5/Tiësto - Boo.flac","trackType":"flac"} Oct 30 12:27:45 volumio volumio[955]: verbose: CURRENT POSITION 36 Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:45 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:45 volumio volumio[955]: info: CorePlayQueue::getTrack 36 Oct 30 12:27:45 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:45 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:45 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:45 volumio volumio[955]: info: CorePlayQueue::getTrack 36 Oct 30 12:27:45 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:45 volumio volumio[955]: info: ------------------------------ 31ms Oct 30 12:27:46 volumio volumio[955]: 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 Oct 30 12:27:46 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:46 volumio volumio[955]: info: CorePlayQueue::getTrack 36 Oct 30 12:27:48 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::play index 35 Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:48 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:48 volumio volumio[955]: info: CorePlayQueue::getTrack 36 Oct 30 12:27:48 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:48 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:48 volumio volumio[955]: info: CorePlayQueue::getTrack 36 Oct 30 12:27:48 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:48 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:48 volumio volumio[955]: info: sendMpdCommand stop took 25 milliseconds Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:48 volumio volumio[955]: info: CorePlayQueue::getTrack 35 Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:48 volumio volumio[955]: info: CorePlayQueue::getTrack 35 Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Flying Steps - Breakin' It Down.mp3 Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:48 volumio volumio[955]: info: Oct 30 12:27:48 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:48 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:48 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:48 volumio volumio[955]: info: Oct 30 12:27:48 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:48 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:48 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:27:48 volumio volumio[955]: info: sendMpdCommand clear took 1 milliseconds Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Flying Steps - Breakin' It Down.mp3" Oct 30 12:27:48 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:48 volumio volumio[955]: info: Oct 30 12:27:48 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:48 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:48 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:27:48 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:48 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Flying Steps - Breakin' It Down.mp3" took 1 milliseconds Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:48 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 12:27:48 volumio volumio[955]: info: ------------------------------ 5ms Oct 30 12:27:48 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:27:48 volumio volumio[955]: info: sendMpdCommand play took 2 milliseconds Oct 30 12:27:48 volumio volumio[955]: info: Oct 30 12:27:48 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:48 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:48 volumio volumio[955]: info: Oct 30 12:27:48 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:48 volumio volumio[955]: info: sendMpdCommand status took 7 milliseconds Oct 30 12:27:48 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:48 volumio volumio[955]: info: sendMpdCommand status took 0 milliseconds Oct 30 12:27:48 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:48 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:48 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:48 volumio volumio[955]: info: CorePlayQueue::getTrack 35 Oct 30 12:27:48 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Breakin' It Down","artist":"Flying Steps","album":"Battle Megamix","uri":"USB/2E38-3CE5/Flying Steps - Breakin' It Down.mp3","trackType":"mp3"} Oct 30 12:27:48 volumio volumio[955]: verbose: CURRENT POSITION 35 Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:48 volumio volumio[955]: info: ------------------------------ 11ms Oct 30 12:27:48 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:48 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:48 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:48 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:48 volumio volumio[955]: info: CorePlayQueue::getTrack 35 Oct 30 12:27:48 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Breakin' It Down","artist":"Flying Steps","album":"Battle Megamix","uri":"USB/2E38-3CE5/Flying Steps - Breakin' It Down.mp3","trackType":"mp3"} Oct 30 12:27:48 volumio volumio[955]: verbose: CURRENT POSITION 35 Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:48 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:48 volumio volumio[955]: info: CorePlayQueue::getTrack 35 Oct 30 12:27:48 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:48 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:48 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:48 volumio volumio[955]: info: CorePlayQueue::getTrack 35 Oct 30 12:27:48 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:48 volumio volumio[955]: info: ------------------------------ 20ms Oct 30 12:27:49 volumio volumio[955]: 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 Oct 30 12:27:49 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:49 volumio volumio[955]: info: CorePlayQueue::getTrack 35 Oct 30 12:27:50 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::play index 34 Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:50 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:50 volumio volumio[955]: info: CorePlayQueue::getTrack 35 Oct 30 12:27:50 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:50 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:50 volumio volumio[955]: info: CorePlayQueue::getTrack 35 Oct 30 12:27:50 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:50 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:50 volumio volumio[955]: info: Oct 30 12:27:50 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:50 volumio volumio[955]: info: sendMpdCommand stop took 26 milliseconds Oct 30 12:27:50 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:50 volumio volumio[955]: info: CorePlayQueue::getTrack 34 Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:50 volumio volumio[955]: info: CorePlayQueue::getTrack 34 Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/ZHU - Bad And Boujee (ZHU Remix).mp3 Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:50 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:50 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:50 volumio volumio[955]: info: Oct 30 12:27:50 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:50 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:50 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:27:50 volumio volumio[955]: info: sendMpdCommand clear took 2 milliseconds Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/ZHU - Bad And Boujee (ZHU Remix).mp3" Oct 30 12:27:50 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:50 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:50 volumio volumio[955]: info: CorePlayQueue::getTrack 34 Oct 30 12:27:50 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:50 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:50 volumio volumio[955]: info: CorePlayQueue::getTrack 34 Oct 30 12:27:50 volumio volumio[955]: 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":"Breakin' It Down","artist":"Flying Steps","album":"Battle Megamix","uri":"USB/2E38-3CE5/Flying Steps - Breakin' It Down.mp3","trackType":"mp3"} Oct 30 12:27:50 volumio volumio[955]: verbose: CURRENT POSITION 34 Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::syncState stateService stop Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:50 volumio volumio[955]: info: CorePlayQueue::getTrack 34 Oct 30 12:27:50 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:50 volumio volumio[955]: info: No code Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:50 volumio volumio[955]: info: CorePlayQueue::getTrack 34 Oct 30 12:27:50 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:50 volumio volumio[955]: info: ------------------------------ 15ms Oct 30 12:27:50 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:50 volumio volumio[955]: info: Oct 30 12:27:50 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:50 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:50 volumio volumio[955]: info: ------------------------------ 13ms Oct 30 12:27:50 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/ZHU - Bad And Boujee (ZHU Remix).mp3" took 12 milliseconds Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:50 volumio volumio[955]: info: Oct 30 12:27:50 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:50 volumio volumio[955]: info: ------------------------------ 11ms Oct 30 12:27:50 volumio volumio[955]: info: sendMpdCommand play took 11 milliseconds Oct 30 12:27:50 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:50 volumio volumio[955]: info: Oct 30 12:27:50 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:50 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:50 volumio volumio[955]: info: sendMpdCommand status took 11 milliseconds Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:50 volumio volumio[955]: info: sendMpdCommand status took 7 milliseconds Oct 30 12:27:50 volumio volumio[955]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:50 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:50 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:50 volumio volumio[955]: info: CorePlayQueue::getTrack 34 Oct 30 12:27:50 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":355,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bad And Boujee (ZHU Remix)","artist":"ZHU","album":"Bad And Boujee (ZHU Remix)","uri":"USB/2E38-3CE5/ZHU - Bad And Boujee (ZHU Remix).mp3","trackType":"mp3"} Oct 30 12:27:50 volumio volumio[955]: verbose: CURRENT POSITION 34 Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:50 volumio volumio[955]: info: ------------------------------ 21ms Oct 30 12:27:50 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:27:50 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:50 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:50 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:50 volumio volumio[955]: info: CorePlayQueue::getTrack 34 Oct 30 12:27:50 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":355,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bad And Boujee (ZHU Remix)","artist":"ZHU","album":"Bad And Boujee (ZHU Remix)","uri":"USB/2E38-3CE5/ZHU - Bad And Boujee (ZHU Remix).mp3","trackType":"mp3"} Oct 30 12:27:50 volumio volumio[955]: verbose: CURRENT POSITION 34 Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:50 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:50 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:50 volumio volumio[955]: info: CorePlayQueue::getTrack 34 Oct 30 12:27:50 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:50 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:51 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:51 volumio volumio[955]: info: CorePlayQueue::getTrack 34 Oct 30 12:27:51 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:51 volumio volumio[955]: info: ------------------------------ 29ms Oct 30 12:27:51 volumio volumio[955]: 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 Oct 30 12:27:51 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:51 volumio volumio[955]: info: CorePlayQueue::getTrack 34 Oct 30 12:27:54 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:54 volumio volumio[955]: info: CoreStateMachine::play index 33 Oct 30 12:27:54 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:54 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:54 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:54 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:54 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:54 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:54 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:54 volumio volumio[955]: info: CorePlayQueue::getTrack 34 Oct 30 12:27:54 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:54 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:54 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:54 volumio volumio[955]: info: CorePlayQueue::getTrack 34 Oct 30 12:27:54 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:54 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:54 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:55 volumio volumio[955]: info: sendMpdCommand stop took 34 milliseconds Oct 30 12:27:55 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:55 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:55 volumio volumio[955]: info: CorePlayQueue::getTrack 33 Oct 30 12:27:55 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:55 volumio volumio[955]: info: CorePlayQueue::getTrack 33 Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Migos - Bad and Boujee (Explicit).mp3 Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:55 volumio volumio[955]: info: Oct 30 12:27:55 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:55 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:55 volumio volumio[955]: info: sendMpdCommand stop took 0 milliseconds Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:55 volumio volumio[955]: info: Oct 30 12:27:55 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:55 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:55 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:55 volumio volumio[955]: info: sendMpdCommand clear took 1 milliseconds Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Migos - Bad and Boujee (Explicit).mp3" Oct 30 12:27:55 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:55 volumio volumio[955]: info: Oct 30 12:27:55 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:55 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:55 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:27:55 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:55 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Migos - Bad and Boujee (Explicit).mp3" took 2 milliseconds Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:55 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 12:27:55 volumio volumio[955]: info: ------------------------------ 5ms Oct 30 12:27:55 volumio volumio[955]: info: ------------------------------ 3ms Oct 30 12:27:55 volumio volumio[955]: info: Oct 30 12:27:55 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:55 volumio volumio[955]: info: sendMpdCommand play took 2 milliseconds Oct 30 12:27:55 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:55 volumio volumio[955]: info: Oct 30 12:27:55 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:55 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:55 volumio volumio[955]: info: sendMpdCommand status took 8 milliseconds Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:55 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:27:55 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:55 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:55 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:55 volumio volumio[955]: info: CorePlayQueue::getTrack 33 Oct 30 12:27:55 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":335,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bad and Boujee (Explicit)","artist":"Migos","album":"Bad and Boujee","uri":"USB/2E38-3CE5/Migos - Bad and Boujee (Explicit).mp3","trackType":"mp3"} Oct 30 12:27:55 volumio volumio[955]: verbose: CURRENT POSITION 33 Oct 30 12:27:55 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:55 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:55 volumio volumio[955]: info: ------------------------------ 12ms Oct 30 12:27:55 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:27:55 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:55 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:55 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:55 volumio volumio[955]: info: CorePlayQueue::getTrack 33 Oct 30 12:27:55 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":335,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bad and Boujee (Explicit)","artist":"Migos","album":"Bad and Boujee","uri":"USB/2E38-3CE5/Migos - Bad and Boujee (Explicit).mp3","trackType":"mp3"} Oct 30 12:27:55 volumio volumio[955]: verbose: CURRENT POSITION 33 Oct 30 12:27:55 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:55 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:55 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:55 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:55 volumio volumio[955]: info: CorePlayQueue::getTrack 33 Oct 30 12:27:55 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:55 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:55 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:55 volumio volumio[955]: info: CorePlayQueue::getTrack 33 Oct 30 12:27:55 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:55 volumio volumio[955]: info: ------------------------------ 18ms Oct 30 12:27:55 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Migos/Bad%20and%20Boujee/91a56a9d-c738-4c85-825d-33c38ae0f635.jpg' Oct 30 12:27:55 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Migos/Bad%20and%20Boujee/91a56a9d-c738-4c85-825d-33c38ae0f635.jpg' Oct 30 12:27:55 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Migos/Bad%20and%20Boujee/91a56a9d-c738-4c85-825d-33c38ae0f635.jpg' Oct 30 12:27:55 volumio volumio[955]: 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 Oct 30 12:27:55 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:55 volumio volumio[955]: info: CorePlayQueue::getTrack 33 Oct 30 12:27:55 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Migos/Bad%20and%20Boujee/91a56a9d-c738-4c85-825d-33c38ae0f635.jpg' Oct 30 12:27:58 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::play index 32 Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:27:58 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:58 volumio volumio[955]: info: CorePlayQueue::getTrack 33 Oct 30 12:27:58 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:58 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:27:58 volumio volumio[955]: info: CorePlayQueue::getTrack 33 Oct 30 12:27:58 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:27:58 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:58 volumio volumio[955]: info: Oct 30 12:27:58 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:58 volumio volumio[955]: info: sendMpdCommand stop took 28 milliseconds Oct 30 12:27:58 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:27:58 volumio volumio[955]: info: CorePlayQueue::getTrack 32 Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:27:58 volumio volumio[955]: info: CorePlayQueue::getTrack 32 Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Audio Tape - Shake Ass.mp3 Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:27:58 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:27:58 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:27:58 volumio volumio[955]: info: Oct 30 12:27:58 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:58 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:58 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:27:58 volumio volumio[955]: info: sendMpdCommand clear took 2 milliseconds Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Audio Tape - Shake Ass.mp3" Oct 30 12:27:58 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:58 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:58 volumio volumio[955]: info: CorePlayQueue::getTrack 32 Oct 30 12:27:58 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:58 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:58 volumio volumio[955]: info: CorePlayQueue::getTrack 32 Oct 30 12:27:58 volumio volumio[955]: 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":"Bad and Boujee (Explicit)","artist":"Migos","album":"Bad and Boujee","uri":"USB/2E38-3CE5/Migos - Bad and Boujee (Explicit).mp3","trackType":"mp3"} Oct 30 12:27:58 volumio volumio[955]: verbose: CURRENT POSITION 32 Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::syncState stateService stop Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:58 volumio volumio[955]: info: CorePlayQueue::getTrack 32 Oct 30 12:27:58 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:58 volumio volumio[955]: info: No code Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:58 volumio volumio[955]: info: CorePlayQueue::getTrack 32 Oct 30 12:27:58 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:58 volumio volumio[955]: info: ------------------------------ 16ms Oct 30 12:27:58 volumio volumio[955]: error: updateQueue error: null Oct 30 12:27:58 volumio volumio[955]: info: Oct 30 12:27:58 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:27:58 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:27:58 volumio volumio[955]: info: ------------------------------ 13ms Oct 30 12:27:58 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Audio Tape - Shake Ass.mp3" took 12 milliseconds Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:27:58 volumio volumio[955]: info: Oct 30 12:27:58 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:58 volumio volumio[955]: info: ------------------------------ 15ms Oct 30 12:27:58 volumio volumio[955]: info: sendMpdCommand play took 15 milliseconds Oct 30 12:27:58 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:58 volumio volumio[955]: info: Oct 30 12:27:58 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:27:58 volumio volumio[955]: info: sendMpdCommand status took 3 milliseconds Oct 30 12:27:58 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:58 volumio volumio[955]: info: sendMpdCommand status took 7 milliseconds Oct 30 12:27:58 volumio volumio[955]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:58 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:58 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:58 volumio volumio[955]: info: CorePlayQueue::getTrack 32 Oct 30 12:27:58 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":110,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Shake Ass","artist":"Audio Tape","album":"Back To 90's","uri":"USB/2E38-3CE5/Audio Tape - Shake Ass.mp3","trackType":"mp3"} Oct 30 12:27:58 volumio volumio[955]: verbose: CURRENT POSITION 32 Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:27:58 volumio volumio[955]: info: ------------------------------ 13ms Oct 30 12:27:58 volumio volumio[955]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 30 12:27:58 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:27:58 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:27:58 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:27:58 volumio volumio[955]: info: CorePlayQueue::getTrack 32 Oct 30 12:27:58 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":110,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Shake Ass","artist":"Audio Tape","album":"Back To 90's","uri":"USB/2E38-3CE5/Audio Tape - Shake Ass.mp3","trackType":"mp3"} Oct 30 12:27:58 volumio volumio[955]: verbose: CURRENT POSITION 32 Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:27:58 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:58 volumio volumio[955]: info: CorePlayQueue::getTrack 32 Oct 30 12:27:58 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:27:58 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:58 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:27:58 volumio volumio[955]: info: CorePlayQueue::getTrack 32 Oct 30 12:27:58 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:27:58 volumio volumio[955]: info: ------------------------------ 31ms Oct 30 12:27:59 volumio volumio[955]: 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 Oct 30 12:27:59 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:27:59 volumio volumio[955]: info: CorePlayQueue::getTrack 32 Oct 30 12:28:00 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::play index 31 Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:28:00 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:00 volumio volumio[955]: info: CorePlayQueue::getTrack 32 Oct 30 12:28:00 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:28:00 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:28:00 volumio volumio[955]: info: CorePlayQueue::getTrack 32 Oct 30 12:28:00 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:28:00 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:28:00 volumio volumio[955]: info: Oct 30 12:28:00 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:00 volumio volumio[955]: info: sendMpdCommand stop took 24 milliseconds Oct 30 12:28:00 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:00 volumio volumio[955]: info: CorePlayQueue::getTrack 31 Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:28:00 volumio volumio[955]: info: CorePlayQueue::getTrack 31 Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Lost Frequencies - Are You With Me (DIMARO Radio Edit).flac Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:28:00 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:28:00 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:28:00 volumio volumio[955]: info: Oct 30 12:28:00 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:28:00 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:28:00 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:28:00 volumio volumio[955]: info: sendMpdCommand clear took 2 milliseconds Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Lost Frequencies - Are You With Me (DIMARO Radio Edit).flac" Oct 30 12:28:00 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:28:00 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:00 volumio volumio[955]: info: CorePlayQueue::getTrack 31 Oct 30 12:28:00 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:28:00 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:00 volumio volumio[955]: info: CorePlayQueue::getTrack 31 Oct 30 12:28:00 volumio volumio[955]: 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":"Shake Ass","artist":"Audio Tape","album":"Back To 90's","uri":"USB/2E38-3CE5/Audio Tape - Shake Ass.mp3","trackType":"mp3"} Oct 30 12:28:00 volumio volumio[955]: verbose: CURRENT POSITION 31 Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::syncState stateService stop Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:00 volumio volumio[955]: info: CorePlayQueue::getTrack 31 Oct 30 12:28:00 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:00 volumio volumio[955]: info: No code Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:00 volumio volumio[955]: info: CorePlayQueue::getTrack 31 Oct 30 12:28:00 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:00 volumio volumio[955]: info: ------------------------------ 20ms Oct 30 12:28:00 volumio volumio[955]: error: updateQueue error: null Oct 30 12:28:00 volumio volumio[955]: info: Oct 30 12:28:00 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:28:00 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:28:00 volumio volumio[955]: info: ------------------------------ 16ms Oct 30 12:28:00 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Lost Frequencies - Are You With Me (DIMARO Radio Edit).flac" took 15 milliseconds Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:28:00 volumio volumio[955]: info: Oct 30 12:28:00 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:00 volumio volumio[955]: info: ------------------------------ 8ms Oct 30 12:28:00 volumio volumio[955]: info: sendMpdCommand play took 7 milliseconds Oct 30 12:28:00 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:00 volumio volumio[955]: info: Oct 30 12:28:00 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:00 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:00 volumio volumio[955]: info: sendMpdCommand status took 4 milliseconds Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:00 volumio volumio[955]: info: sendMpdCommand status took 3 milliseconds Oct 30 12:28:00 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:00 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:28:00 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:28:00 volumio volumio[955]: info: CorePlayQueue::getTrack 31 Oct 30 12:28:00 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":215,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Are You With Me (DIMARO Radio Edit)","artist":"Lost Frequencies","album":"Are You With Me (Remix)","uri":"USB/2E38-3CE5/Lost Frequencies - Are You With Me (DIMARO Radio Edit).flac","trackType":"flac"} Oct 30 12:28:00 volumio volumio[955]: verbose: CURRENT POSITION 31 Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:28:00 volumio volumio[955]: info: ------------------------------ 11ms Oct 30 12:28:00 volumio volumio[955]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 30 12:28:00 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:00 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:28:00 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:28:00 volumio volumio[955]: info: CorePlayQueue::getTrack 31 Oct 30 12:28:00 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":215,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Are You With Me (DIMARO Radio Edit)","artist":"Lost Frequencies","album":"Are You With Me (Remix)","uri":"USB/2E38-3CE5/Lost Frequencies - Are You With Me (DIMARO Radio Edit).flac","trackType":"flac"} Oct 30 12:28:00 volumio volumio[955]: verbose: CURRENT POSITION 31 Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:28:00 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:00 volumio volumio[955]: info: CorePlayQueue::getTrack 31 Oct 30 12:28:00 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:28:00 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:00 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:00 volumio volumio[955]: info: CorePlayQueue::getTrack 31 Oct 30 12:28:00 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:00 volumio volumio[955]: info: ------------------------------ 26ms Oct 30 12:28:01 volumio volumio[955]: 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 Oct 30 12:28:01 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:28:01 volumio volumio[955]: info: CorePlayQueue::getTrack 31 Oct 30 12:28:04 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::play index 30 Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:28:04 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:04 volumio volumio[955]: info: CorePlayQueue::getTrack 31 Oct 30 12:28:04 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:28:04 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:28:04 volumio volumio[955]: info: CorePlayQueue::getTrack 31 Oct 30 12:28:04 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:28:04 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:28:04 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:28:04 volumio volumio[955]: info: Oct 30 12:28:04 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:04 volumio volumio[955]: info: sendMpdCommand stop took 28 milliseconds Oct 30 12:28:04 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:04 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:04 volumio volumio[955]: info: CorePlayQueue::getTrack 30 Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:28:04 volumio volumio[955]: info: CorePlayQueue::getTrack 30 Oct 30 12:28:04 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Rompasso - Angetenar.mp3 Oct 30 12:28:04 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:28:04 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:28:04 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:28:04 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:04 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:04 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:28:04 volumio volumio[955]: info: Oct 30 12:28:04 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:28:04 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:28:04 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:28:04 volumio volumio[955]: info: sendMpdCommand clear took 2 milliseconds Oct 30 12:28:04 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:04 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Rompasso - Angetenar.mp3" Oct 30 12:28:04 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:28:04 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:04 volumio volumio[955]: info: CorePlayQueue::getTrack 30 Oct 30 12:28:04 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:28:04 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:04 volumio volumio[955]: info: CorePlayQueue::getTrack 30 Oct 30 12:28:04 volumio volumio[955]: 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":"Are You With Me (DIMARO Radio Edit)","artist":"Lost Frequencies","album":"Are You With Me (Remix)","uri":"USB/2E38-3CE5/Lost Frequencies - Are You With Me (DIMARO Radio Edit).flac","trackType":"flac"} Oct 30 12:28:04 volumio volumio[955]: verbose: CURRENT POSITION 30 Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::syncState stateService stop Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:04 volumio volumio[955]: info: CorePlayQueue::getTrack 30 Oct 30 12:28:04 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:04 volumio volumio[955]: info: No code Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:04 volumio volumio[955]: info: CorePlayQueue::getTrack 30 Oct 30 12:28:04 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:04 volumio volumio[955]: info: ------------------------------ 18ms Oct 30 12:28:04 volumio volumio[955]: error: updateQueue error: null Oct 30 12:28:04 volumio volumio[955]: info: Oct 30 12:28:04 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:28:04 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:28:04 volumio volumio[955]: info: ------------------------------ 15ms Oct 30 12:28:04 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Rompasso - Angetenar.mp3" took 14 milliseconds Oct 30 12:28:04 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:28:04 volumio volumio[955]: info: Oct 30 12:28:04 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:04 volumio volumio[955]: info: ------------------------------ 22ms Oct 30 12:28:04 volumio volumio[955]: info: sendMpdCommand play took 21 milliseconds Oct 30 12:28:04 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:04 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:04 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Rompasso/Angetenar/8a3990bd-7827-4310-8f4a-37f66cd25c30.jpg' Oct 30 12:28:04 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Rompasso/Angetenar/8a3990bd-7827-4310-8f4a-37f66cd25c30.jpg' Oct 30 12:28:04 volumio volumio[955]: info: sendMpdCommand status took 13 milliseconds Oct 30 12:28:04 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:04 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:04 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:28:04 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:04 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:28:04 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:28:04 volumio volumio[955]: info: CorePlayQueue::getTrack 30 Oct 30 12:28:04 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":366,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Angetenar","artist":"Rompasso","album":"Angetenar","uri":"USB/2E38-3CE5/Rompasso - Angetenar.mp3","trackType":"mp3"} Oct 30 12:28:04 volumio volumio[955]: verbose: CURRENT POSITION 30 Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:28:04 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:28:04 volumio volumio[955]: info: ------------------------------ 18ms Oct 30 12:28:04 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Rompasso/Angetenar/8a3990bd-7827-4310-8f4a-37f66cd25c30.jpg' Oct 30 12:28:05 volumio volumio[955]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 30 12:28:05 volumio volumio[955]: info: Retrying Login Due to Network Error Oct 30 12:28:08 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::play index 29 Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:28:08 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:08 volumio volumio[955]: info: CorePlayQueue::getTrack 30 Oct 30 12:28:08 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:28:08 volumio volumio[955]: info: CorePlayQueue::getTrack 30 Oct 30 12:28:08 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:28:08 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:28:08 volumio volumio[955]: info: sendMpdCommand stop took 34 milliseconds Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:08 volumio volumio[955]: info: CorePlayQueue::getTrack 29 Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:28:08 volumio volumio[955]: info: CorePlayQueue::getTrack 29 Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Fifth Harmony - Work From Home.flac Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:28:08 volumio volumio[955]: info: Oct 30 12:28:08 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:08 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:08 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:28:08 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:08 volumio volumio[955]: info: Oct 30 12:28:08 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:28:08 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:28:08 volumio volumio[955]: info: sendMpdCommand clear took 2 milliseconds Oct 30 12:28:08 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Fifth Harmony - Work From Home.flac" Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:08 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 12:28:08 volumio volumio[955]: info: ------------------------------ 4ms Oct 30 12:28:08 volumio volumio[955]: error: updateQueue error: null Oct 30 12:28:08 volumio volumio[955]: info: Oct 30 12:28:08 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:28:08 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:28:08 volumio volumio[955]: info: ------------------------------ 3ms Oct 30 12:28:08 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Fifth Harmony - Work From Home.flac" took 2 milliseconds Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:28:08 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:28:08 volumio volumio[955]: info: Oct 30 12:28:08 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:08 volumio volumio[955]: info: sendMpdCommand play took 2 milliseconds Oct 30 12:28:08 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:08 volumio volumio[955]: info: Oct 30 12:28:08 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:08 volumio volumio[955]: info: sendMpdCommand status took 8 milliseconds Oct 30 12:28:08 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:08 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:28:08 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:08 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:28:08 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:28:08 volumio volumio[955]: info: CorePlayQueue::getTrack 29 Oct 30 12:28:08 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":215,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"644 Kbps","isStreaming":false,"title":"Fifth Harmony - Work From Home.flac","artist":null,"album":"7/27 (Deluxe)","uri":"USB/2E38-3CE5/Fifth Harmony - Work From Home.flac","trackType":"flac"} Oct 30 12:28:08 volumio volumio[955]: verbose: CURRENT POSITION 29 Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:28:08 volumio volumio[955]: info: ------------------------------ 13ms Oct 30 12:28:08 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:28:08 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:08 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:28:08 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:28:08 volumio volumio[955]: info: CorePlayQueue::getTrack 29 Oct 30 12:28:08 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":215,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"604 Kbps","isStreaming":false,"title":"Fifth Harmony - Work From Home.flac","artist":null,"album":"7/27 (Deluxe)","uri":"USB/2E38-3CE5/Fifth Harmony - Work From Home.flac","trackType":"flac"} Oct 30 12:28:08 volumio volumio[955]: verbose: CURRENT POSITION 29 Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:28:08 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:08 volumio volumio[955]: info: CorePlayQueue::getTrack 29 Oct 30 12:28:08 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:28:08 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:08 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:08 volumio volumio[955]: info: CorePlayQueue::getTrack 29 Oct 30 12:28:08 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:08 volumio volumio[955]: info: ------------------------------ 13ms Oct 30 12:28:09 volumio volumio[955]: 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 Oct 30 12:28:09 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:28:09 volumio volumio[955]: info: CorePlayQueue::getTrack 29 Oct 30 12:28:10 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:28:10 volumio volumio[955]: info: CoreStateMachine::play index 28 Oct 30 12:28:10 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:10 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:28:10 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:10 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:28:10 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:28:10 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:28:10 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:10 volumio volumio[955]: info: CorePlayQueue::getTrack 29 Oct 30 12:28:10 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:28:10 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:10 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:28:10 volumio volumio[955]: info: CorePlayQueue::getTrack 29 Oct 30 12:28:10 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:28:10 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:28:10 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:28:11 volumio volumio[955]: info: sendMpdCommand stop took 29 milliseconds Oct 30 12:28:11 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:28:11 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:11 volumio volumio[955]: info: CorePlayQueue::getTrack 28 Oct 30 12:28:11 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:28:11 volumio volumio[955]: info: CorePlayQueue::getTrack 28 Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Dr. Dre - Still D.R.E. (Instrumental Version).mp3 Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:28:11 volumio volumio[955]: info: Oct 30 12:28:11 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:11 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:11 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:28:11 volumio volumio[955]: info: Oct 30 12:28:11 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:28:11 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:28:11 volumio volumio[955]: info: sendMpdCommand status took 6 milliseconds Oct 30 12:28:11 volumio volumio[955]: info: sendMpdCommand clear took 5 milliseconds Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Dr. Dre - Still D.R.E. (Instrumental Version).mp3" Oct 30 12:28:11 volumio volumio[955]: error: updateQueue error: null Oct 30 12:28:11 volumio volumio[955]: info: Oct 30 12:28:11 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:28:11 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:28:11 volumio volumio[955]: info: ------------------------------ 7ms Oct 30 12:28:11 volumio volumio[955]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 30 12:28:11 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Dr. Dre - Still D.R.E. (Instrumental Version).mp3" took 6 milliseconds Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:28:11 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 12:28:11 volumio volumio[955]: info: ------------------------------ 14ms Oct 30 12:28:11 volumio volumio[955]: info: Oct 30 12:28:11 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:11 volumio volumio[955]: info: ------------------------------ 3ms Oct 30 12:28:11 volumio volumio[955]: info: sendMpdCommand play took 2 milliseconds Oct 30 12:28:11 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:11 volumio volumio[955]: info: Oct 30 12:28:11 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:11 volumio volumio[955]: info: sendMpdCommand status took 8 milliseconds Oct 30 12:28:11 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:11 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:28:11 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:11 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:28:11 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:28:11 volumio volumio[955]: info: CorePlayQueue::getTrack 28 Oct 30 12:28:11 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":263,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Still D.R.E. (Instrumental Version)","artist":"Dr. Dre","album":"2001 Instrumental","uri":"USB/2E38-3CE5/Dr. Dre - Still D.R.E. (Instrumental Version).mp3","trackType":"mp3"} Oct 30 12:28:11 volumio volumio[955]: verbose: CURRENT POSITION 28 Oct 30 12:28:11 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:28:11 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:28:11 volumio volumio[955]: info: ------------------------------ 12ms Oct 30 12:28:11 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:28:11 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:11 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:28:11 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:28:11 volumio volumio[955]: info: CorePlayQueue::getTrack 28 Oct 30 12:28:11 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":263,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Still D.R.E. (Instrumental Version)","artist":"Dr. Dre","album":"2001 Instrumental","uri":"USB/2E38-3CE5/Dr. Dre - Still D.R.E. (Instrumental Version).mp3","trackType":"mp3"} Oct 30 12:28:11 volumio volumio[955]: verbose: CURRENT POSITION 28 Oct 30 12:28:11 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:28:11 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:28:11 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:28:11 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:11 volumio volumio[955]: info: CorePlayQueue::getTrack 28 Oct 30 12:28:11 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:28:11 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:11 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:11 volumio volumio[955]: info: CorePlayQueue::getTrack 28 Oct 30 12:28:11 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:11 volumio volumio[955]: info: ------------------------------ 14ms Oct 30 12:28:11 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dr.%20Dre/2001%20Instrumental/b4389240-7536-430d-b6f7-71293d0d0eba.jpg' Oct 30 12:28:11 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dr.%20Dre/2001%20Instrumental/b4389240-7536-430d-b6f7-71293d0d0eba.jpg' Oct 30 12:28:11 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dr.%20Dre/2001%20Instrumental/b4389240-7536-430d-b6f7-71293d0d0eba.jpg' Oct 30 12:28:11 volumio volumio[955]: 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 Oct 30 12:28:11 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:28:11 volumio volumio[955]: info: CorePlayQueue::getTrack 28 Oct 30 12:28:11 volumio volumio[955]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dr.%20Dre/2001%20Instrumental/b4389240-7536-430d-b6f7-71293d0d0eba.jpg' Oct 30 12:28:13 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::play index 27 Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:28:13 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:13 volumio volumio[955]: info: CorePlayQueue::getTrack 28 Oct 30 12:28:13 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:28:13 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:28:13 volumio volumio[955]: info: CorePlayQueue::getTrack 28 Oct 30 12:28:13 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:28:13 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:28:13 volumio volumio[955]: info: sendMpdCommand stop took 28 milliseconds Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:13 volumio volumio[955]: info: CorePlayQueue::getTrack 27 Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:28:13 volumio volumio[955]: info: CorePlayQueue::getTrack 27 Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Dr. Dre - The Next Episode.flac Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:28:13 volumio volumio[955]: info: Oct 30 12:28:13 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:13 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:13 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:28:13 volumio volumio[955]: info: Oct 30 12:28:13 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:28:13 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:28:13 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:28:13 volumio volumio[955]: info: sendMpdCommand clear took 1 milliseconds Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Dr. Dre - The Next Episode.flac" Oct 30 12:28:13 volumio volumio[955]: error: updateQueue error: null Oct 30 12:28:13 volumio volumio[955]: info: Oct 30 12:28:13 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:28:13 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:28:13 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:28:13 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:28:13 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Dr. Dre - The Next Episode.flac" took 1 milliseconds Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:28:13 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 12:28:13 volumio volumio[955]: info: ------------------------------ 6ms Oct 30 12:28:13 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:28:13 volumio volumio[955]: info: sendMpdCommand play took 2 milliseconds Oct 30 12:28:13 volumio volumio[955]: info: Oct 30 12:28:13 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:13 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:13 volumio volumio[955]: info: Oct 30 12:28:13 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:13 volumio volumio[955]: info: sendMpdCommand status took 7 milliseconds Oct 30 12:28:13 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:13 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:28:13 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:13 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:28:13 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:28:13 volumio volumio[955]: info: CorePlayQueue::getTrack 27 Oct 30 12:28:13 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":163,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dr. Dre - The Next Episode.flac","artist":null,"album":"2001","uri":"USB/2E38-3CE5/Dr. Dre - The Next Episode.flac","trackType":"flac"} Oct 30 12:28:13 volumio volumio[955]: verbose: CURRENT POSITION 27 Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:28:13 volumio volumio[955]: info: ------------------------------ 12ms Oct 30 12:28:13 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:28:13 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:13 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:28:13 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:28:13 volumio volumio[955]: info: CorePlayQueue::getTrack 27 Oct 30 12:28:13 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":163,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dr. Dre - The Next Episode.flac","artist":null,"album":"2001","uri":"USB/2E38-3CE5/Dr. Dre - The Next Episode.flac","trackType":"flac"} Oct 30 12:28:13 volumio volumio[955]: verbose: CURRENT POSITION 27 Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:28:13 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:13 volumio volumio[955]: info: CorePlayQueue::getTrack 27 Oct 30 12:28:13 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:28:13 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:13 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:13 volumio volumio[955]: info: CorePlayQueue::getTrack 27 Oct 30 12:28:13 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:13 volumio volumio[955]: info: ------------------------------ 13ms Oct 30 12:28:14 volumio volumio[955]: 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 Oct 30 12:28:14 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:28:14 volumio volumio[955]: info: CorePlayQueue::getTrack 27 Oct 30 12:28:15 volumio volumio[955]: info: MyVolumio login type: Token Oct 30 12:28:15 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::play index 26 Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:28:15 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:15 volumio volumio[955]: info: CorePlayQueue::getTrack 27 Oct 30 12:28:15 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:28:15 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:28:15 volumio volumio[955]: info: CorePlayQueue::getTrack 27 Oct 30 12:28:15 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:28:15 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:28:15 volumio volumio[955]: info: Oct 30 12:28:15 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:15 volumio volumio[955]: info: sendMpdCommand stop took 27 milliseconds Oct 30 12:28:15 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:15 volumio volumio[955]: info: CorePlayQueue::getTrack 26 Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:28:15 volumio volumio[955]: info: CorePlayQueue::getTrack 26 Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/Boom(Extended_Mix)-Tiesto_Sevenn-26734518.flac Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:28:15 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Oct 30 12:28:15 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:28:15 volumio volumio[955]: info: Oct 30 12:28:15 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:28:15 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:28:15 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:28:15 volumio volumio[955]: info: sendMpdCommand clear took 1 milliseconds Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/Boom(Extended_Mix)-Tiesto_Sevenn-26734518.flac" Oct 30 12:28:15 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:28:15 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:15 volumio volumio[955]: info: CorePlayQueue::getTrack 26 Oct 30 12:28:15 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:28:15 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:15 volumio volumio[955]: info: CorePlayQueue::getTrack 26 Oct 30 12:28:15 volumio volumio[955]: 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":"Dr. Dre - The Next Episode.flac","artist":null,"album":"2001","uri":"USB/2E38-3CE5/Dr. Dre - The Next Episode.flac","trackType":"flac"} Oct 30 12:28:15 volumio volumio[955]: verbose: CURRENT POSITION 26 Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::syncState stateService stop Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:15 volumio volumio[955]: info: CorePlayQueue::getTrack 26 Oct 30 12:28:15 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:15 volumio volumio[955]: info: No code Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:15 volumio volumio[955]: info: CorePlayQueue::getTrack 26 Oct 30 12:28:15 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:15 volumio volumio[955]: info: ------------------------------ 18ms Oct 30 12:28:15 volumio volumio[955]: error: updateQueue error: null Oct 30 12:28:15 volumio volumio[955]: info: Oct 30 12:28:15 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:28:15 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:28:15 volumio volumio[955]: info: ------------------------------ 14ms Oct 30 12:28:15 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/Boom(Extended_Mix)-Tiesto_Sevenn-26734518.flac" took 13 milliseconds Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:28:15 volumio volumio[955]: info: Oct 30 12:28:15 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:15 volumio volumio[955]: info: ------------------------------ 13ms Oct 30 12:28:15 volumio volumio[955]: info: sendMpdCommand play took 12 milliseconds Oct 30 12:28:15 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:15 volumio volumio[955]: info: Oct 30 12:28:15 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:15 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:15 volumio volumio[955]: info: sendMpdCommand status took 7 milliseconds Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:15 volumio volumio[955]: info: sendMpdCommand status took 3 milliseconds Oct 30 12:28:15 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:15 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:28:15 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:28:15 volumio volumio[955]: info: CorePlayQueue::getTrack 26 Oct 30 12:28:15 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":569,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"693 Kbps","isStreaming":false,"title":"Boom(Extended Mix)","artist":"Tiesto&Sevenn","album":"【歌单】Beatport全球电子舞曲榜(2017-05-04)","uri":"USB/2E38-3CE5/Boom(Extended_Mix)-Tiesto_Sevenn-26734518.flac","trackType":"flac"} Oct 30 12:28:15 volumio volumio[955]: verbose: CURRENT POSITION 26 Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:28:15 volumio volumio[955]: info: ------------------------------ 13ms Oct 30 12:28:15 volumio volumio[955]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 30 12:28:15 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:15 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:28:15 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:28:15 volumio volumio[955]: info: CorePlayQueue::getTrack 26 Oct 30 12:28:15 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"663 Kbps","isStreaming":false,"title":"Boom(Extended Mix)","artist":"Tiesto&Sevenn","album":"【歌单】Beatport全球电子舞曲榜(2017-05-04)","uri":"USB/2E38-3CE5/Boom(Extended_Mix)-Tiesto_Sevenn-26734518.flac","trackType":"flac"} Oct 30 12:28:15 volumio volumio[955]: verbose: CURRENT POSITION 26 Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:28:15 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:15 volumio volumio[955]: info: CorePlayQueue::getTrack 26 Oct 30 12:28:15 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:28:15 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:15 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:15 volumio volumio[955]: info: CorePlayQueue::getTrack 26 Oct 30 12:28:15 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:15 volumio volumio[955]: info: ------------------------------ 45ms Oct 30 12:28:16 volumio volumio[955]: 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 Oct 30 12:28:16 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:28:16 volumio volumio[955]: info: CorePlayQueue::getTrack 26 Oct 30 12:28:18 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::play index 25 Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::stop Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 30 12:28:18 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:18 volumio volumio[955]: info: CorePlayQueue::getTrack 26 Oct 30 12:28:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:28:18 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::serviceStop Oct 30 12:28:18 volumio volumio[955]: info: CorePlayQueue::getTrack 26 Oct 30 12:28:18 volumio volumio[955]: info: CoreCommandRouter::serviceStop Oct 30 12:28:18 volumio volumio[955]: info: ControllerMpd::stop Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:28:18 volumio volumio[955]: info: sendMpdCommand stop took 33 milliseconds Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 12:28:18 volumio volumio[955]: info: CorePlayQueue::getTrack 25 Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 30 12:28:18 volumio volumio[955]: info: CorePlayQueue::getTrack 25 Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/2E38-3CE5/07.做你心上人.wav Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 12:28:18 volumio volumio[955]: info: Oct 30 12:28:18 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:18 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:18 volumio volumio[955]: info: sendMpdCommand stop took 1 milliseconds Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 12:28:18 volumio volumio[955]: info: Oct 30 12:28:18 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:28:18 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:28:18 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:28:18 volumio volumio[955]: info: sendMpdCommand clear took 1 milliseconds Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "USB/2E38-3CE5/07.做你心上人.wav" Oct 30 12:28:18 volumio volumio[955]: error: updateQueue error: null Oct 30 12:28:18 volumio volumio[955]: info: Oct 30 12:28:18 volumio volumio[955]: ---------------------------- MPD announces system playlist update Oct 30 12:28:18 volumio volumio[955]: info: Ignoring MPD Status Update Oct 30 12:28:18 volumio volumio[955]: info: ------------------------------ 2ms Oct 30 12:28:18 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:28:18 volumio volumio[955]: info: sendMpdCommand add "USB/2E38-3CE5/07.做你心上人.wav" took 2 milliseconds Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Oct 30 12:28:18 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 12:28:18 volumio volumio[955]: info: ------------------------------ 5ms Oct 30 12:28:18 volumio volumio[955]: info: Oct 30 12:28:18 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:18 volumio volumio[955]: info: ------------------------------ 4ms Oct 30 12:28:18 volumio volumio[955]: info: sendMpdCommand play took 3 milliseconds Oct 30 12:28:18 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:18 volumio volumio[955]: info: Oct 30 12:28:18 volumio volumio[955]: ---------------------------- MPD announces state update: player Oct 30 12:28:18 volumio volumio[955]: info: ControllerMpd::getState Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Oct 30 12:28:18 volumio volumio[955]: info: sendMpdCommand status took 8 milliseconds Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:18 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Oct 30 12:28:18 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::parseState Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:18 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:28:18 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:28:18 volumio volumio[955]: info: CorePlayQueue::getTrack 25 Oct 30 12:28:18 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"07.做你心上人.wav","artist":"?????????","album":"????","uri":"USB/2E38-3CE5/07.做你心上人.wav","trackType":"wav"} Oct 30 12:28:18 volumio volumio[955]: verbose: CURRENT POSITION 25 Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Oct 30 12:28:18 volumio volumio[955]: info: ------------------------------ 13ms Oct 30 12:28:18 volumio volumio[955]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 12:28:18 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Oct 30 12:28:18 volumio volumio[955]: info: ControllerMpd::pushState Oct 30 12:28:18 volumio volumio[955]: info: CoreCommandRouter::servicePushState Oct 30 12:28:18 volumio volumio[955]: info: CorePlayQueue::getTrack 25 Oct 30 12:28:18 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"07.做你心上人.wav","artist":"?????????","album":"????","uri":"USB/2E38-3CE5/07.做你心上人.wav","trackType":"wav"} Oct 30 12:28:18 volumio volumio[955]: verbose: CURRENT POSITION 25 Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Oct 30 12:28:18 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:18 volumio volumio[955]: info: CorePlayQueue::getTrack 25 Oct 30 12:28:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 12:28:18 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:18 volumio volumio[955]: info: CoreStateMachine::pushState Oct 30 12:28:18 volumio volumio[955]: info: CorePlayQueue::getTrack 25 Oct 30 12:28:18 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 30 12:28:18 volumio volumio[955]: info: ------------------------------ 15ms Oct 30 12:28:19 volumio volumio[955]: 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 Oct 30 12:28:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 30 12:28:19 volumio volumio[955]: info: CorePlayQueue::getTrack 25 Oct 30 12:28:24 volumio volumio[955]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 12:28:24 volumio volumio[955]: Error: connect ETIMEDOUT 104.244.43.52:443 Oct 30 12:28:24 volumio volumio[955]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 30 12:28:24 volumio volumio[955]: errno: -110, Oct 30 12:28:24 volumio volumio[955]: code: 'ETIMEDOUT', Oct 30 12:28:24 volumio volumio[955]: syscall: 'connect', Oct 30 12:28:24 volumio volumio[955]: address: '104.244.43.52', Oct 30 12:28:24 volumio volumio[955]: port: 443 Oct 30 12:28:24 volumio volumio[955]: } Oct 30 12:28:24 volumio volumio[955]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 12:28:24 volumio sudo[5417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-30 12:27 Oct 30 12:28:24 volumio sudo[5417]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"