-- Logs begin at Wed 2026-02-25 11:05:10 MSK, end at Thu 2026-02-26 13:35:28 MSK. -- Feb 26 13:34:00 volumio volumio[994]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 26 13:34:00 volumio volumio[994]: info: CoreStateMachine::removeQueueItem Feb 26 13:34:00 volumio volumio[994]: info: CorePlayQueue::removeQueueItem 8 Feb 26 13:34:00 volumio volumio[994]: info: CorePlayQueue::saveQueue Feb 26 13:34:00 volumio volumio[994]: info: CoreCommandRouter::volumioPushQueue Feb 26 13:34:05 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Feb 26 13:34:05 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:05 volumio volumio[994]: info: Listing playlists Feb 26 13:34:05 volumio volumio[994]: info: Listing playlists Feb 26 13:34:11 volumio volumio[994]: info: CoreCommandRouter::volumioSaveQueueToPlaylist Feb 26 13:34:11 volumio volumio[994]: info: CoreStateMachine::getQueue Feb 26 13:34:11 volumio volumio[994]: info: CorePlayQueue::getQueue Feb 26 13:34:11 volumio volumio[994]: info: Saving local item Relax Feb 26 13:34:15 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Feb 26 13:34:15 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::volumioPlay Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::play index 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::stop Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::updateTrackBlock Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrackBlock Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::serviceStop Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::serviceStop Feb 26 13:34:18 volumio volumio[994]: info: ControllerMpd::stop Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:34:18 volumio volumio[994]: info: sendMpdCommand stop took 1 milliseconds Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::play index undefined Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::startPlaybackTimer Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3 Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:34:18 volumio volumio[994]: info: Feb 26 13:34:18 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:18 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:18 volumio volumio[994]: info: sendMpdCommand stop took 0 milliseconds Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 13:34:18 volumio volumio[994]: info: Feb 26 13:34:18 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:18 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:18 volumio volumio[994]: info: Feb 26 13:34:18 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:18 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:18 volumio volumio[994]: info: Feb 26 13:34:18 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:18 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:18 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:18 volumio volumio[994]: info: sendMpdCommand clear took 1 milliseconds Feb 26 13:34:18 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:18 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3" Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:18 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:34:18 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:18 volumio volumio[994]: info: No code Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:18 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:34:18 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:18 volumio volumio[994]: info: No code Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:18 volumio volumio[994]: info: ------------------------------ 6ms Feb 26 13:34:18 volumio volumio[994]: info: ------------------------------ 6ms Feb 26 13:34:18 volumio volumio[994]: info: Feb 26 13:34:18 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:18 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:18 volumio volumio[994]: info: Feb 26 13:34:18 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:18 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:18 volumio volumio[994]: error: updateQueue error: null Feb 26 13:34:18 volumio volumio[994]: info: Feb 26 13:34:18 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:18 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:18 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:34:18 volumio volumio[994]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 26 13:34:18 volumio volumio[994]: info: sendMpdCommand add "INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3" took 9 milliseconds Feb 26 13:34:18 volumio volumio[994]: info: ------------------------------ 3ms Feb 26 13:34:18 volumio volumio[994]: info: ------------------------------ 3ms Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand play Feb 26 13:34:18 volumio volumio[994]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 26 13:34:18 volumio volumio[994]: info: ------------------------------ 10ms Feb 26 13:34:18 volumio volumio[994]: info: Feb 26 13:34:18 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:18 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:18 volumio volumio[994]: info: Feb 26 13:34:18 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:18 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:18 volumio volumio[994]: info: ------------------------------ 5ms Feb 26 13:34:18 volumio volumio[994]: info: Feb 26 13:34:18 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:18 volumio volumio[994]: info: sendMpdCommand play took 8 milliseconds Feb 26 13:34:18 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:18 volumio volumio[994]: info: ------------------------------ 8ms Feb 26 13:34:18 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:34:18 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:18 volumio volumio[994]: info: Feb 26 13:34:18 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:18 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:18 volumio volumio[994]: info: Feb 26 13:34:18 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:18 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:18 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:34:18 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:18 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"acceptance","artist":"Øneheart/DVRST","album":"Boyhood","uri":"INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","trackType":"mp3"} Feb 26 13:34:18 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:18 volumio volumio[994]: info: ------------------------------ 2ms Feb 26 13:34:18 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:18 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:18 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"acceptance","artist":"Øneheart/DVRST","album":"Boyhood","uri":"INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","trackType":"mp3"} Feb 26 13:34:18 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:18 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:18 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"acceptance","artist":"Øneheart/DVRST","album":"Boyhood","uri":"INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","trackType":"mp3"} Feb 26 13:34:18 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:18 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:18 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:18 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:18 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:18 volumio volumio[994]: info: ------------------------------ 4ms Feb 26 13:34:18 volumio volumio[994]: info: ------------------------------ 4ms Feb 26 13:34:25 volumio volumio[994]: info: CoreCommandRouter::volumioMoveQueue Feb 26 13:34:25 volumio volumio[994]: info: CoreStateMachine::moveQueueItem 7 7 Feb 26 13:34:25 volumio volumio[994]: info: CorePlayQueue::moveQueueItem 7 --> 7 Feb 26 13:34:25 volumio volumio[994]: info: CoreCommandRouter::volumioPushQueue Feb 26 13:34:25 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Feb 26 13:34:25 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:25 volumio volumio[994]: info: Listing playlists Feb 26 13:34:25 volumio volumio[994]: info: Listing playlists Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::volumioPlay Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::play index 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::stop Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::updateTrackBlock Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrackBlock Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::serviceStop Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::serviceStop Feb 26 13:34:27 volumio volumio[994]: info: ControllerMpd::stop Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:34:27 volumio volumio[994]: info: sendMpdCommand stop took 1 milliseconds Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::play index undefined Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::startPlaybackTimer Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3 Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:34:27 volumio volumio[994]: info: Feb 26 13:34:27 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:27 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:27 volumio volumio[994]: info: sendMpdCommand stop took 0 milliseconds Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 13:34:27 volumio volumio[994]: info: Feb 26 13:34:27 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:27 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:27 volumio volumio[994]: info: Feb 26 13:34:27 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:27 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:27 volumio volumio[994]: info: Feb 26 13:34:27 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:27 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:27 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:27 volumio volumio[994]: info: sendMpdCommand clear took 1 milliseconds Feb 26 13:34:27 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:27 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3" Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:27 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:34:27 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:27 volumio volumio[994]: info: No code Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:27 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:34:27 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:27 volumio volumio[994]: info: No code Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:27 volumio volumio[994]: info: ------------------------------ 8ms Feb 26 13:34:27 volumio volumio[994]: info: ------------------------------ 8ms Feb 26 13:34:27 volumio volumio[994]: info: Feb 26 13:34:27 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:27 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:27 volumio volumio[994]: info: Feb 26 13:34:27 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:27 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:27 volumio volumio[994]: error: updateQueue error: null Feb 26 13:34:27 volumio volumio[994]: info: Feb 26 13:34:27 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:27 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:27 volumio volumio[994]: info: ------------------------------ 9ms Feb 26 13:34:27 volumio volumio[994]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 26 13:34:27 volumio volumio[994]: info: sendMpdCommand add "INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3" took 8 milliseconds Feb 26 13:34:27 volumio volumio[994]: info: ------------------------------ 2ms Feb 26 13:34:27 volumio volumio[994]: info: ------------------------------ 2ms Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand play Feb 26 13:34:27 volumio volumio[994]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 26 13:34:27 volumio volumio[994]: info: ------------------------------ 11ms Feb 26 13:34:27 volumio volumio[994]: info: Feb 26 13:34:27 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:27 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:27 volumio volumio[994]: info: Feb 26 13:34:27 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:27 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:27 volumio volumio[994]: info: ------------------------------ 3ms Feb 26 13:34:27 volumio volumio[994]: info: Feb 26 13:34:27 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:27 volumio volumio[994]: info: sendMpdCommand play took 6 milliseconds Feb 26 13:34:27 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:27 volumio volumio[994]: info: ------------------------------ 6ms Feb 26 13:34:27 volumio volumio[994]: info: ------------------------------ 5ms Feb 26 13:34:27 volumio volumio[994]: info: Feb 26 13:34:27 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:27 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:27 volumio volumio[994]: info: Feb 26 13:34:27 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:27 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:27 volumio volumio[994]: info: sendMpdCommand status took 4 milliseconds Feb 26 13:34:27 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:27 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:27 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:34:27 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:34:27 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:27 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:27 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"acceptance","artist":"Øneheart/DVRST","album":"Boyhood","uri":"INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","trackType":"mp3"} Feb 26 13:34:27 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:27 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"acceptance","artist":"Øneheart/DVRST","album":"Boyhood","uri":"INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","trackType":"mp3"} Feb 26 13:34:27 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:27 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:27 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"acceptance","artist":"Øneheart/DVRST","album":"Boyhood","uri":"INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","trackType":"mp3"} Feb 26 13:34:27 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:27 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:27 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:27 volumio volumio[994]: info: ------------------------------ 11ms Feb 26 13:34:27 volumio volumio[994]: info: ------------------------------ 9ms Feb 26 13:34:27 volumio volumio[994]: info: ------------------------------ 8ms Feb 26 13:34:35 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Feb 26 13:34:35 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:35 volumio volumio[994]: info: CoreCommandRouter::volumioPause Feb 26 13:34:35 volumio volumio[994]: info: CoreStateMachine::pause Feb 26 13:34:35 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Feb 26 13:34:35 volumio volumio[994]: info: CoreStateMachine::servicePause Feb 26 13:34:35 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:35 volumio volumio[994]: info: CoreCommandRouter::servicePause Feb 26 13:34:35 volumio volumio[994]: info: ControllerMpd::pause Feb 26 13:34:35 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand pause Feb 26 13:34:35 volumio volumio[994]: info: sendMpdCommand pause took 0 milliseconds Feb 26 13:34:35 volumio volumio[994]: info: Feb 26 13:34:35 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:35 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:35 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:35 volumio volumio[994]: info: Feb 26 13:34:35 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:35 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:35 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:35 volumio volumio[994]: info: Feb 26 13:34:35 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:35 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:35 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:35 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:35 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:35 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:35 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:35 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:35 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:35 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:35 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:34:35 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:34:35 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:35 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:35 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:35 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:35 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:35 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:35 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:35 volumio volumio[994]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9083,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"acceptance","artist":"Øneheart/DVRST","album":"Boyhood","uri":"INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","trackType":"mp3"} Feb 26 13:34:35 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:35 volumio volumio[994]: info: CoreStateMachine::syncState stateService pause Feb 26 13:34:35 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus pause Feb 26 13:34:35 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:35 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:35 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:35 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:35 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Feb 26 13:34:35 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:35 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:35 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:35 volumio volumio[994]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9083,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"acceptance","artist":"Øneheart/DVRST","album":"Boyhood","uri":"INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","trackType":"mp3"} Feb 26 13:34:35 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:35 volumio volumio[994]: info: CoreStateMachine::syncState stateService pause Feb 26 13:34:35 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus pause Feb 26 13:34:35 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:35 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:35 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:35 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Feb 26 13:34:35 volumio volumio[994]: info: ------------------------------ 3ms Feb 26 13:34:35 volumio volumio[994]: info: ------------------------------ 3ms Feb 26 13:34:35 volumio volumio[994]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 13:34:35 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:35 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:35 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:35 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:35 volumio volumio[994]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9083,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"acceptance","artist":"Øneheart/DVRST","album":"Boyhood","uri":"INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","trackType":"mp3"} Feb 26 13:34:35 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:35 volumio volumio[994]: info: CoreStateMachine::syncState stateService pause Feb 26 13:34:35 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus pause Feb 26 13:34:35 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:35 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:35 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:35 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Feb 26 13:34:35 volumio volumio[994]: info: ------------------------------ 5ms Feb 26 13:34:41 volumio volumio[994]: info: CoreCommandRouter::volumioMoveQueue Feb 26 13:34:41 volumio volumio[994]: info: CoreStateMachine::moveQueueItem 1 1 Feb 26 13:34:41 volumio volumio[994]: info: CorePlayQueue::moveQueueItem 1 --> 1 Feb 26 13:34:41 volumio volumio[994]: info: CoreCommandRouter::volumioPushQueue Feb 26 13:34:43 volumio volumio[994]: info: CoreCommandRouter::volumioMoveQueue Feb 26 13:34:43 volumio volumio[994]: info: CoreStateMachine::moveQueueItem 4 1 Feb 26 13:34:43 volumio volumio[994]: info: CorePlayQueue::moveQueueItem 4 --> 1 Feb 26 13:34:43 volumio volumio[994]: info: CoreCommandRouter::volumioPushQueue Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::volumioPlay Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::play index 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::stop Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::updateTrackBlock Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrackBlock Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::serviceStop Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::serviceStop Feb 26 13:34:44 volumio volumio[994]: info: ControllerMpd::stop Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:34:44 volumio volumio[994]: info: sendMpdCommand stop took 1 milliseconds Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::play index undefined Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::startPlaybackTimer Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/!MP3/RELAX/01- DJ Creepy_ Deep House - Cloud.mp3 Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:34:44 volumio volumio[994]: info: Feb 26 13:34:44 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:44 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:44 volumio volumio[994]: info: sendMpdCommand stop took 0 milliseconds Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 13:34:44 volumio volumio[994]: info: Feb 26 13:34:44 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:44 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:44 volumio volumio[994]: info: Feb 26 13:34:44 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:44 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:44 volumio volumio[994]: info: Feb 26 13:34:44 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:44 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:44 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:44 volumio volumio[994]: info: sendMpdCommand clear took 1 milliseconds Feb 26 13:34:44 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:44 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/!MP3/RELAX/01- DJ Creepy_ Deep House - Cloud.mp3" Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:44 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:34:44 volumio volumio[994]: verbose: CURRENT POSITION 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:44 volumio volumio[994]: info: No code Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:44 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:34:44 volumio volumio[994]: verbose: CURRENT POSITION 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:44 volumio volumio[994]: info: No code Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:44 volumio volumio[994]: info: ------------------------------ 9ms Feb 26 13:34:44 volumio volumio[994]: info: ------------------------------ 9ms Feb 26 13:34:44 volumio volumio[994]: info: Feb 26 13:34:44 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:44 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:44 volumio volumio[994]: info: Feb 26 13:34:44 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:44 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:44 volumio volumio[994]: error: updateQueue error: null Feb 26 13:34:44 volumio volumio[994]: info: Feb 26 13:34:44 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:44 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:44 volumio volumio[994]: info: ------------------------------ 10ms Feb 26 13:34:44 volumio volumio[994]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 26 13:34:44 volumio volumio[994]: info: sendMpdCommand add "INTERNAL/!MP3/RELAX/01- DJ Creepy_ Deep House - Cloud.mp3" took 10 milliseconds Feb 26 13:34:44 volumio volumio[994]: info: ------------------------------ 1ms Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand play Feb 26 13:34:44 volumio volumio[994]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 26 13:34:44 volumio volumio[994]: info: ------------------------------ 11ms Feb 26 13:34:44 volumio volumio[994]: info: Feb 26 13:34:44 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:44 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:44 volumio volumio[994]: info: Feb 26 13:34:44 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:44 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:44 volumio volumio[994]: info: ------------------------------ 3ms Feb 26 13:34:44 volumio volumio[994]: info: Feb 26 13:34:44 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:44 volumio volumio[994]: info: sendMpdCommand play took 6 milliseconds Feb 26 13:34:44 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:44 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:34:44 volumio volumio[994]: info: Feb 26 13:34:44 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:44 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:44 volumio volumio[994]: info: Feb 26 13:34:44 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:44 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:44 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:44 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:44 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:44 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:34:44 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:34:44 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:44 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:44 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":196,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"01- DJ Creepy_ Deep House - Cloud.mp3","artist":null,"album":null,"uri":"INTERNAL/!MP3/RELAX/01- DJ Creepy_ Deep House - Cloud.mp3","trackType":"mp3"} Feb 26 13:34:44 volumio volumio[994]: verbose: CURRENT POSITION 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:44 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":196,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"01- DJ Creepy_ Deep House - Cloud.mp3","artist":null,"album":null,"uri":"INTERNAL/!MP3/RELAX/01- DJ Creepy_ Deep House - Cloud.mp3","trackType":"mp3"} Feb 26 13:34:44 volumio volumio[994]: verbose: CURRENT POSITION 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:44 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:44 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":196,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"01- DJ Creepy_ Deep House - Cloud.mp3","artist":null,"album":null,"uri":"INTERNAL/!MP3/RELAX/01- DJ Creepy_ Deep House - Cloud.mp3","trackType":"mp3"} Feb 26 13:34:44 volumio volumio[994]: verbose: CURRENT POSITION 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:44 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:44 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:44 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:44 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:44 volumio volumio[994]: info: ------------------------------ 9ms Feb 26 13:34:44 volumio volumio[994]: info: ------------------------------ 11ms Feb 26 13:34:44 volumio volumio[994]: info: ------------------------------ 12ms Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:45 volumio volumio[994]: info: Listing playlists Feb 26 13:34:45 volumio volumio[994]: info: Listing playlists Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::volumioNext Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::next Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::stop Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::updateTrackBlock Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrackBlock Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::serviceStop Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::serviceStop Feb 26 13:34:45 volumio volumio[994]: info: ControllerMpd::stop Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:34:45 volumio volumio[994]: info: Feb 26 13:34:45 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:45 volumio volumio[994]: info: sendMpdCommand stop took 2 milliseconds Feb 26 13:34:45 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::play index undefined Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::startPlaybackTimer Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3 Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::updateTrackBlock Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrackBlock Feb 26 13:34:45 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:45 volumio volumio[994]: info: sendMpdCommand stop took 1 milliseconds Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 13:34:45 volumio volumio[994]: info: Feb 26 13:34:45 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:45 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:45 volumio volumio[994]: info: Feb 26 13:34:45 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:45 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:45 volumio volumio[994]: info: Feb 26 13:34:45 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:45 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:45 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:34:45 volumio volumio[994]: info: sendMpdCommand clear took 1 milliseconds Feb 26 13:34:45 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:45 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3" Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:45 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: 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":"01- DJ Creepy_ Deep House - Cloud.mp3","artist":null,"album":null,"uri":"INTERNAL/!MP3/RELAX/01- DJ Creepy_ Deep House - Cloud.mp3","trackType":"mp3"} Feb 26 13:34:45 volumio volumio[994]: verbose: CURRENT POSITION 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:45 volumio volumio[994]: info: No code Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:45 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:34:45 volumio volumio[994]: verbose: CURRENT POSITION 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:45 volumio volumio[994]: info: No code Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:45 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:34:45 volumio volumio[994]: verbose: CURRENT POSITION 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:45 volumio volumio[994]: info: No code Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:45 volumio volumio[994]: info: ------------------------------ 10ms Feb 26 13:34:45 volumio volumio[994]: info: ------------------------------ 8ms Feb 26 13:34:45 volumio volumio[994]: info: ------------------------------ 9ms Feb 26 13:34:45 volumio volumio[994]: info: Feb 26 13:34:45 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:45 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:45 volumio volumio[994]: info: Feb 26 13:34:45 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:45 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:45 volumio volumio[994]: error: updateQueue error: null Feb 26 13:34:45 volumio volumio[994]: info: Feb 26 13:34:45 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:45 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:45 volumio volumio[994]: info: ------------------------------ 8ms Feb 26 13:34:45 volumio volumio[994]: info: sendMpdCommand add "INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3" took 8 milliseconds Feb 26 13:34:45 volumio volumio[994]: info: ------------------------------ 1ms Feb 26 13:34:45 volumio volumio[994]: info: ------------------------------ 1ms Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand play Feb 26 13:34:45 volumio volumio[994]: info: Feb 26 13:34:45 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:45 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:45 volumio volumio[994]: info: Feb 26 13:34:45 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:45 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:45 volumio volumio[994]: info: ------------------------------ 3ms Feb 26 13:34:45 volumio volumio[994]: info: sendMpdCommand play took 7 milliseconds Feb 26 13:34:45 volumio volumio[994]: info: Feb 26 13:34:45 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:45 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:45 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:34:45 volumio volumio[994]: info: Feb 26 13:34:45 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:45 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:45 volumio volumio[994]: info: Feb 26 13:34:45 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:45 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:45 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:45 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:45 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:45 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:34:45 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:34:45 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:45 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:45 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"acceptance","artist":"Øneheart/DVRST","album":"Boyhood","uri":"INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","trackType":"mp3"} Feb 26 13:34:45 volumio volumio[994]: verbose: CURRENT POSITION 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:45 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"acceptance","artist":"Øneheart/DVRST","album":"Boyhood","uri":"INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","trackType":"mp3"} Feb 26 13:34:45 volumio volumio[994]: verbose: CURRENT POSITION 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:45 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:45 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"acceptance","artist":"Øneheart/DVRST","album":"Boyhood","uri":"INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","trackType":"mp3"} Feb 26 13:34:45 volumio volumio[994]: verbose: CURRENT POSITION 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:45 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:45 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:45 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:45 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:45 volumio volumio[994]: info: ------------------------------ 6ms Feb 26 13:34:45 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:34:45 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:34:49 volumio volumio[994]: info: CoreCommandRouter::volumioSeek Feb 26 13:34:49 volumio volumio[994]: info: CoreStateMachine::seek Feb 26 13:34:49 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:49 volumio volumio[994]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","service":"mpd","name":"12 - acceptance","artist":"Øneheart/DVRST","album":"Boyhood","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=305&web=%C3%98neheart%2FDVRST/Boyhood/extralarge&path=%2FINTERNAL%2F!MP3%2FRELAX&metadata=false","duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Feb 26 13:34:49 volumio volumio[994]: info: CoreStateMachine::startPlaybackTimer Feb 26 13:34:49 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:49 volumio volumio[994]: info: ControllerMpd::seek Feb 26 13:34:49 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:49 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:49 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:49 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:49 volumio volumio[994]: error: null Feb 26 13:34:49 volumio volumio[994]: info: Feb 26 13:34:49 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:49 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:49 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:49 volumio volumio[994]: info: Feb 26 13:34:49 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:49 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:49 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:49 volumio volumio[994]: info: Feb 26 13:34:49 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:49 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:49 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:49 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:49 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:49 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:49 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:49 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:49 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:49 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:49 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:49 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:49 volumio volumio[994]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 13:34:49 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:34:49 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:34:49 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:49 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:49 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:49 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:49 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:49 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:49 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41000,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"acceptance","artist":"Øneheart/DVRST","album":"Boyhood","uri":"INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","trackType":"mp3"} Feb 26 13:34:49 volumio volumio[994]: verbose: CURRENT POSITION 1 Feb 26 13:34:49 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:49 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:49 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:49 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:49 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:49 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:49 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:49 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:49 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:49 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:49 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:49 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:49 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41000,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"acceptance","artist":"Øneheart/DVRST","album":"Boyhood","uri":"INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","trackType":"mp3"} Feb 26 13:34:49 volumio volumio[994]: verbose: CURRENT POSITION 1 Feb 26 13:34:49 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:49 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:49 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:49 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:49 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:49 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:49 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:49 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:49 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:49 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:49 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:49 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:49 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41000,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"acceptance","artist":"Øneheart/DVRST","album":"Boyhood","uri":"INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","trackType":"mp3"} Feb 26 13:34:49 volumio volumio[994]: verbose: CURRENT POSITION 1 Feb 26 13:34:49 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:49 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:49 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:49 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:49 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:49 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:49 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:49 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:49 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:49 volumio volumio[994]: info: ------------------------------ 6ms Feb 26 13:34:49 volumio volumio[994]: info: ------------------------------ 6ms Feb 26 13:34:49 volumio volumio[994]: info: ------------------------------ 6ms Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::volumioNext Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::next Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::stop Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::updateTrackBlock Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrackBlock Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::serviceStop Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrack 1 Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::serviceStop Feb 26 13:34:50 volumio volumio[994]: info: ControllerMpd::stop Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:34:50 volumio volumio[994]: info: Feb 26 13:34:50 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand stop took 2 milliseconds Feb 26 13:34:50 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::play index undefined Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::startPlaybackTimer Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/!MP3/RELAX/Astral_Hang_-_Harmony_80785402.mp3 Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::updateTrackBlock Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrackBlock Feb 26 13:34:50 volumio volumio[994]: info: Feb 26 13:34:50 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:50 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:50 volumio volumio[994]: info: Feb 26 13:34:50 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:50 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand stop took 1 milliseconds Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:50 volumio volumio[994]: info: Feb 26 13:34:50 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:50 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand clear took 0 milliseconds Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/!MP3/RELAX/Astral_Hang_-_Harmony_80785402.mp3" Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:50 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:50 volumio volumio[994]: 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":"acceptance","artist":"Øneheart/DVRST","album":"Boyhood","uri":"INTERNAL/!MP3/RELAX/02-Oneheart, Dvrst - Acceptance (hitmos.fm).mp3","trackType":"mp3"} Feb 26 13:34:50 volumio volumio[994]: verbose: CURRENT POSITION 2 Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:50 volumio volumio[994]: info: No code Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:50 volumio volumio[994]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 26 13:34:50 volumio volumio[994]: info: ------------------------------ 5ms Feb 26 13:34:50 volumio volumio[994]: info: Feb 26 13:34:50 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:50 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:50 volumio volumio[994]: info: Feb 26 13:34:50 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:50 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:50 volumio volumio[994]: error: updateQueue error: null Feb 26 13:34:50 volumio volumio[994]: info: Feb 26 13:34:50 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:50 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:50 volumio volumio[994]: info: ------------------------------ 5ms Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand add "INTERNAL/!MP3/RELAX/Astral_Hang_-_Harmony_80785402.mp3" took 4 milliseconds Feb 26 13:34:50 volumio volumio[994]: info: ------------------------------ 1ms Feb 26 13:34:50 volumio volumio[994]: info: ------------------------------ 1ms Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand play Feb 26 13:34:50 volumio volumio[994]: info: Feb 26 13:34:50 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:50 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:50 volumio volumio[994]: info: Feb 26 13:34:50 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:50 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:50 volumio volumio[994]: info: ------------------------------ 2ms Feb 26 13:34:50 volumio volumio[994]: info: Feb 26 13:34:50 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand play took 9 milliseconds Feb 26 13:34:50 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:50 volumio volumio[994]: info: ------------------------------ 9ms Feb 26 13:34:50 volumio volumio[994]: info: ------------------------------ 9ms Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:50 volumio volumio[994]: info: Feb 26 13:34:50 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:50 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:50 volumio volumio[994]: info: Feb 26 13:34:50 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:50 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:50 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:50 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Harmony","artist":"Astral Hang","album":null,"uri":"INTERNAL/!MP3/RELAX/Astral_Hang_-_Harmony_80785402.mp3","trackType":"mp3"} Feb 26 13:34:50 volumio volumio[994]: verbose: CURRENT POSITION 2 Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:50 volumio volumio[994]: info: ------------------------------ 1ms Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:34:50 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:50 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:50 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:50 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Harmony","artist":"Astral Hang","album":null,"uri":"INTERNAL/!MP3/RELAX/Astral_Hang_-_Harmony_80785402.mp3","trackType":"mp3"} Feb 26 13:34:50 volumio volumio[994]: verbose: CURRENT POSITION 2 Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:50 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:50 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:50 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Harmony","artist":"Astral Hang","album":null,"uri":"INTERNAL/!MP3/RELAX/Astral_Hang_-_Harmony_80785402.mp3","trackType":"mp3"} Feb 26 13:34:50 volumio volumio[994]: verbose: CURRENT POSITION 2 Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:50 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:50 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:50 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:50 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:50 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:34:53 volumio volumio[994]: info: CoreCommandRouter::volumioSeek Feb 26 13:34:53 volumio volumio[994]: info: CoreStateMachine::seek Feb 26 13:34:53 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:53 volumio volumio[994]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/!MP3/RELAX/Astral_Hang_-_Harmony_80785402.mp3","service":"mpd","name":"Harmony","artist":"Astral Hang","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=305&web=Astral%20Hang//extralarge&path=%2FINTERNAL%2F!MP3%2FRELAX&metadata=false","duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Feb 26 13:34:53 volumio volumio[994]: info: CoreStateMachine::startPlaybackTimer Feb 26 13:34:53 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:53 volumio volumio[994]: info: ControllerMpd::seek Feb 26 13:34:53 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:53 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:53 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:53 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:53 volumio volumio[994]: error: null Feb 26 13:34:53 volumio volumio[994]: info: Feb 26 13:34:53 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:53 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:53 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:53 volumio volumio[994]: info: Feb 26 13:34:53 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:53 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:53 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:53 volumio volumio[994]: info: Feb 26 13:34:53 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:53 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:53 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:53 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:53 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:53 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:53 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:53 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:53 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:53 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:53 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:34:53 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:34:53 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:53 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:53 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:53 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:53 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:53 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:53 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:53 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":89000,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Harmony","artist":"Astral Hang","album":null,"uri":"INTERNAL/!MP3/RELAX/Astral_Hang_-_Harmony_80785402.mp3","trackType":"mp3"} Feb 26 13:34:53 volumio volumio[994]: verbose: CURRENT POSITION 2 Feb 26 13:34:53 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:53 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:53 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:53 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:53 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:53 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:53 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:53 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:53 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:53 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:53 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:53 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:53 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":89000,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Harmony","artist":"Astral Hang","album":null,"uri":"INTERNAL/!MP3/RELAX/Astral_Hang_-_Harmony_80785402.mp3","trackType":"mp3"} Feb 26 13:34:53 volumio volumio[994]: verbose: CURRENT POSITION 2 Feb 26 13:34:53 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:53 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:53 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:53 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:53 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:53 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:53 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:53 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:53 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:53 volumio volumio[994]: info: ------------------------------ 4ms Feb 26 13:34:53 volumio volumio[994]: info: ------------------------------ 4ms Feb 26 13:34:53 volumio volumio[994]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 26 13:34:53 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:53 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:53 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:53 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:53 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":89000,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Harmony","artist":"Astral Hang","album":null,"uri":"INTERNAL/!MP3/RELAX/Astral_Hang_-_Harmony_80785402.mp3","trackType":"mp3"} Feb 26 13:34:53 volumio volumio[994]: verbose: CURRENT POSITION 2 Feb 26 13:34:53 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:53 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:53 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:53 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:53 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:53 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:53 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:53 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:53 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:53 volumio volumio[994]: info: ------------------------------ 5ms Feb 26 13:34:55 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Feb 26 13:34:55 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::volumioPlay Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::play index 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::stop Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::updateTrackBlock Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrackBlock Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::serviceStop Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 2 Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::serviceStop Feb 26 13:34:57 volumio volumio[994]: info: ControllerMpd::stop Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:34:57 volumio volumio[994]: info: sendMpdCommand stop took 1 milliseconds Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::play index undefined Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::startPlaybackTimer Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/!MP3/RELAX/Track No08.mp3 Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:34:57 volumio volumio[994]: info: Feb 26 13:34:57 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:57 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:57 volumio volumio[994]: info: sendMpdCommand stop took 1 milliseconds Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 13:34:57 volumio volumio[994]: info: Feb 26 13:34:57 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:57 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:57 volumio volumio[994]: info: Feb 26 13:34:57 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:57 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:57 volumio volumio[994]: info: Feb 26 13:34:57 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:57 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:57 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:57 volumio volumio[994]: info: sendMpdCommand clear took 0 milliseconds Feb 26 13:34:57 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:57 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/!MP3/RELAX/Track No08.mp3" Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:57 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:34:57 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:57 volumio volumio[994]: info: No code Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:57 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:34:57 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:57 volumio volumio[994]: info: No code Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:57 volumio volumio[994]: info: ------------------------------ 5ms Feb 26 13:34:57 volumio volumio[994]: info: Feb 26 13:34:57 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:57 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:57 volumio volumio[994]: info: Feb 26 13:34:57 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:57 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:57 volumio volumio[994]: error: updateQueue error: null Feb 26 13:34:57 volumio volumio[994]: info: Feb 26 13:34:57 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:57 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:57 volumio volumio[994]: info: ------------------------------ 6ms Feb 26 13:34:57 volumio volumio[994]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 26 13:34:57 volumio volumio[994]: info: sendMpdCommand add "INTERNAL/!MP3/RELAX/Track No08.mp3" took 6 milliseconds Feb 26 13:34:57 volumio volumio[994]: info: ------------------------------ 1ms Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand play Feb 26 13:34:57 volumio volumio[994]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 26 13:34:57 volumio volumio[994]: info: ------------------------------ 8ms Feb 26 13:34:57 volumio volumio[994]: info: Feb 26 13:34:57 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:57 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:57 volumio volumio[994]: info: Feb 26 13:34:57 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:34:57 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:34:57 volumio volumio[994]: info: ------------------------------ 3ms Feb 26 13:34:57 volumio volumio[994]: info: Feb 26 13:34:57 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:57 volumio volumio[994]: info: sendMpdCommand play took 4 milliseconds Feb 26 13:34:57 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:57 volumio volumio[994]: info: ------------------------------ 4ms Feb 26 13:34:57 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:57 volumio volumio[994]: info: Feb 26 13:34:57 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:57 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:57 volumio volumio[994]: info: Feb 26 13:34:57 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:57 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:57 volumio volumio[994]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 13:34:57 volumio volumio[994]: info: sendMpdCommand status took 2 milliseconds Feb 26 13:34:57 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:57 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":477,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track No08.mp3","artist":null,"album":null,"uri":"INTERNAL/!MP3/RELAX/Track No08.mp3","trackType":"mp3"} Feb 26 13:34:57 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:34:57 volumio volumio[994]: info: ------------------------------ 3ms Feb 26 13:34:57 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:34:57 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:34:57 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:57 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":477,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track No08.mp3","artist":null,"album":null,"uri":"INTERNAL/!MP3/RELAX/Track No08.mp3","trackType":"mp3"} Feb 26 13:34:57 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:57 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:57 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":477,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track No08.mp3","artist":null,"album":null,"uri":"INTERNAL/!MP3/RELAX/Track No08.mp3","trackType":"mp3"} Feb 26 13:34:57 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:57 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:57 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:57 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:57 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:57 volumio volumio[994]: info: ------------------------------ 6ms Feb 26 13:34:57 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:34:58 volumio volumio[994]: info: CoreCommandRouter::volumioSeek Feb 26 13:34:58 volumio volumio[994]: info: CoreStateMachine::seek Feb 26 13:34:58 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:58 volumio volumio[994]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/!MP3/RELAX/Track No08.mp3","service":"mpd","name":"Track No08.mp3","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=305&web=//extralarge&path=%2FINTERNAL%2F!MP3%2FRELAX&metadata=false","duration":477,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Feb 26 13:34:58 volumio volumio[994]: info: CoreStateMachine::startPlaybackTimer Feb 26 13:34:58 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:58 volumio volumio[994]: info: ControllerMpd::seek Feb 26 13:34:58 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:58 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:58 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:34:58 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:58 volumio volumio[994]: error: null Feb 26 13:34:58 volumio volumio[994]: info: Feb 26 13:34:58 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:58 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:58 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:58 volumio volumio[994]: info: Feb 26 13:34:58 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:58 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:58 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:58 volumio volumio[994]: info: Feb 26 13:34:58 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:34:58 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:34:58 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:34:58 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:34:58 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:58 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:34:58 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:58 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:58 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:58 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:58 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:34:58 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:34:58 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:34:58 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:34:58 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:34:58 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:58 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:58 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:34:58 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:58 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:58 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:58 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":180000,"duration":477,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track No08.mp3","artist":null,"album":null,"uri":"INTERNAL/!MP3/RELAX/Track No08.mp3","trackType":"mp3"} Feb 26 13:34:58 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:58 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:58 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:58 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:58 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:58 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:58 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:58 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:58 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:58 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:58 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:58 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:58 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:58 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":180000,"duration":477,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track No08.mp3","artist":null,"album":null,"uri":"INTERNAL/!MP3/RELAX/Track No08.mp3","trackType":"mp3"} Feb 26 13:34:58 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:58 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:58 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:58 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:58 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:58 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:58 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:58 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:58 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:58 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:58 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:34:58 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:34:58 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:58 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":180000,"duration":477,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track No08.mp3","artist":null,"album":null,"uri":"INTERNAL/!MP3/RELAX/Track No08.mp3","trackType":"mp3"} Feb 26 13:34:58 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:34:58 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:34:58 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:34:58 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:34:58 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:58 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:58 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:58 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:34:58 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:34:58 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:34:58 volumio volumio[994]: info: ------------------------------ 9ms Feb 26 13:34:58 volumio volumio[994]: info: ------------------------------ 8ms Feb 26 13:35:03 volumio volumio[994]: info: CoreCommandRouter::volumioMoveQueue Feb 26 13:35:03 volumio volumio[994]: info: CoreStateMachine::moveQueueItem 4 3 Feb 26 13:35:03 volumio volumio[994]: info: CorePlayQueue::moveQueueItem 4 --> 3 Feb 26 13:35:03 volumio volumio[994]: info: CoreCommandRouter::volumioPushQueue Feb 26 13:35:05 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Feb 26 13:35:05 volumio volumio[994]: info: CorePlayQueue::getTrack 3 Feb 26 13:35:05 volumio volumio[994]: info: Listing playlists Feb 26 13:35:05 volumio volumio[994]: info: Listing playlists Feb 26 13:35:05 volumio volumio[994]: info: CoreCommandRouter::volumioMoveQueue Feb 26 13:35:05 volumio volumio[994]: info: CoreStateMachine::moveQueueItem 5 4 Feb 26 13:35:05 volumio volumio[994]: info: CorePlayQueue::moveQueueItem 5 --> 4 Feb 26 13:35:05 volumio volumio[994]: info: CoreCommandRouter::volumioPushQueue Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::volumioPlay Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::play index 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::stop Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::updateTrackBlock Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrackBlock Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 3 Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::serviceStop Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 3 Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::serviceStop Feb 26 13:35:06 volumio volumio[994]: info: ControllerMpd::stop Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:35:06 volumio volumio[994]: info: sendMpdCommand stop took 1 milliseconds Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::play index undefined Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::startPlaybackTimer Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/!MP3/RELAX/Mystique_Munroe_-_Infinity_77857202.mp3 Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:35:06 volumio volumio[994]: info: Feb 26 13:35:06 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:06 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:06 volumio volumio[994]: info: sendMpdCommand stop took 1 milliseconds Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 13:35:06 volumio volumio[994]: info: Feb 26 13:35:06 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:06 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:06 volumio volumio[994]: info: Feb 26 13:35:06 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:06 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:06 volumio volumio[994]: info: Feb 26 13:35:06 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:06 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:06 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:35:06 volumio volumio[994]: info: sendMpdCommand clear took 1 milliseconds Feb 26 13:35:06 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:35:06 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/!MP3/RELAX/Mystique_Munroe_-_Infinity_77857202.mp3" Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:06 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:35:06 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:06 volumio volumio[994]: info: No code Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:06 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:35:06 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:06 volumio volumio[994]: info: No code Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:06 volumio volumio[994]: info: ------------------------------ 5ms Feb 26 13:35:06 volumio volumio[994]: info: ------------------------------ 5ms Feb 26 13:35:06 volumio volumio[994]: info: Feb 26 13:35:06 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:06 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:06 volumio volumio[994]: info: Feb 26 13:35:06 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:06 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:06 volumio volumio[994]: error: updateQueue error: null Feb 26 13:35:06 volumio volumio[994]: info: Feb 26 13:35:06 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:06 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:06 volumio volumio[994]: info: ------------------------------ 6ms Feb 26 13:35:06 volumio volumio[994]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 26 13:35:06 volumio volumio[994]: info: sendMpdCommand add "INTERNAL/!MP3/RELAX/Mystique_Munroe_-_Infinity_77857202.mp3" took 5 milliseconds Feb 26 13:35:06 volumio volumio[994]: info: ------------------------------ 1ms Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand play Feb 26 13:35:06 volumio volumio[994]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 26 13:35:06 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:35:06 volumio volumio[994]: info: Feb 26 13:35:06 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:06 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:06 volumio volumio[994]: info: Feb 26 13:35:06 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:06 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:06 volumio volumio[994]: info: ------------------------------ 3ms Feb 26 13:35:06 volumio volumio[994]: info: Feb 26 13:35:06 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:06 volumio volumio[994]: info: sendMpdCommand play took 10 milliseconds Feb 26 13:35:06 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:06 volumio volumio[994]: info: ------------------------------ 9ms Feb 26 13:35:06 volumio volumio[994]: info: Feb 26 13:35:06 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:06 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:06 volumio volumio[994]: info: Feb 26 13:35:06 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:06 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:06 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:35:06 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:35:06 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:06 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:35:06 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:35:06 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:06 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:06 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Infinity","artist":"Mystique Munroe","album":"Infinity","uri":"INTERNAL/!MP3/RELAX/Mystique_Munroe_-_Infinity_77857202.mp3","trackType":"mp3"} Feb 26 13:35:06 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:35:06 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Infinity","artist":"Mystique Munroe","album":"Infinity","uri":"INTERNAL/!MP3/RELAX/Mystique_Munroe_-_Infinity_77857202.mp3","trackType":"mp3"} Feb 26 13:35:06 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:35:06 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:06 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Infinity","artist":"Mystique Munroe","album":"Infinity","uri":"INTERNAL/!MP3/RELAX/Mystique_Munroe_-_Infinity_77857202.mp3","trackType":"mp3"} Feb 26 13:35:06 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:35:06 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:06 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:06 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:06 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:06 volumio volumio[994]: info: ------------------------------ 4ms Feb 26 13:35:06 volumio volumio[994]: info: ------------------------------ 4ms Feb 26 13:35:06 volumio volumio[994]: info: ------------------------------ 5ms Feb 26 13:35:07 volumio volumio[994]: info: CoreCommandRouter::volumioSeek Feb 26 13:35:07 volumio volumio[994]: info: CoreStateMachine::seek Feb 26 13:35:07 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:07 volumio volumio[994]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/!MP3/RELAX/Mystique_Munroe_-_Infinity_77857202.mp3","service":"mpd","name":"Infinity","artist":"Mystique Munroe","album":"Infinity","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=305&web=Mystique%20Munroe/Infinity/extralarge&path=%2FINTERNAL%2F!MP3%2FRELAX&metadata=false","duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Feb 26 13:35:07 volumio volumio[994]: info: CoreStateMachine::startPlaybackTimer Feb 26 13:35:07 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:07 volumio volumio[994]: info: ControllerMpd::seek Feb 26 13:35:07 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:07 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:07 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:35:07 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:07 volumio volumio[994]: error: null Feb 26 13:35:07 volumio volumio[994]: info: Feb 26 13:35:07 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:07 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:07 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:07 volumio volumio[994]: info: Feb 26 13:35:07 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:07 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:07 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:07 volumio volumio[994]: info: Feb 26 13:35:07 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:07 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:07 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:07 volumio volumio[994]: info: sendMpdCommand status took 2 milliseconds Feb 26 13:35:07 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:35:07 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:35:07 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:07 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:07 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:07 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:07 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:07 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:07 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:35:07 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:35:07 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:35:07 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:07 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:07 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:07 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:07 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:07 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:07 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67887,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Infinity","artist":"Mystique Munroe","album":"Infinity","uri":"INTERNAL/!MP3/RELAX/Mystique_Munroe_-_Infinity_77857202.mp3","trackType":"mp3"} Feb 26 13:35:07 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:35:07 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:07 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:35:07 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:35:07 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:07 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:07 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:35:07 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:07 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:07 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:07 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:07 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:07 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:07 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:07 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67887,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Infinity","artist":"Mystique Munroe","album":"Infinity","uri":"INTERNAL/!MP3/RELAX/Mystique_Munroe_-_Infinity_77857202.mp3","trackType":"mp3"} Feb 26 13:35:07 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:35:07 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:07 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:35:07 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:35:07 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:07 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:07 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:07 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:07 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:07 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:07 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:07 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:07 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:07 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67887,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Infinity","artist":"Mystique Munroe","album":"Infinity","uri":"INTERNAL/!MP3/RELAX/Mystique_Munroe_-_Infinity_77857202.mp3","trackType":"mp3"} Feb 26 13:35:07 volumio volumio[994]: verbose: CURRENT POSITION 4 Feb 26 13:35:07 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:07 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:35:07 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:35:07 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:07 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:07 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:07 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:07 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:07 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:07 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:35:07 volumio volumio[994]: info: ------------------------------ 6ms Feb 26 13:35:07 volumio volumio[994]: info: ------------------------------ 6ms Feb 26 13:35:14 volumio volumio[994]: info: CoreCommandRouter::volumioMoveQueue Feb 26 13:35:14 volumio volumio[994]: info: CoreStateMachine::moveQueueItem 7 5 Feb 26 13:35:14 volumio volumio[994]: info: CorePlayQueue::moveQueueItem 7 --> 5 Feb 26 13:35:14 volumio volumio[994]: info: CoreCommandRouter::volumioPushQueue Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::volumioPlay Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::play index 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::stop Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::updateTrackBlock Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrackBlock Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::serviceStop Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 4 Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::serviceStop Feb 26 13:35:15 volumio volumio[994]: info: ControllerMpd::stop Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:35:15 volumio volumio[994]: info: sendMpdCommand stop took 2 milliseconds Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::play index undefined Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::startPlaybackTimer Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/!MP3/RELAX/RelaxSleep_-_Moment_Harmony_80399661.mp3 Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:35:15 volumio volumio[994]: info: Feb 26 13:35:15 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:15 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:15 volumio volumio[994]: info: sendMpdCommand stop took 0 milliseconds Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 13:35:15 volumio volumio[994]: info: Feb 26 13:35:15 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:15 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:15 volumio volumio[994]: info: Feb 26 13:35:15 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:15 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:15 volumio volumio[994]: info: Feb 26 13:35:15 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:15 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:15 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:35:15 volumio volumio[994]: info: sendMpdCommand clear took 1 milliseconds Feb 26 13:35:15 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:35:15 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/!MP3/RELAX/RelaxSleep_-_Moment_Harmony_80399661.mp3" Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:15 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:35:15 volumio volumio[994]: verbose: CURRENT POSITION 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:15 volumio volumio[994]: info: No code Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:15 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:35:15 volumio volumio[994]: verbose: CURRENT POSITION 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:15 volumio volumio[994]: info: No code Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:15 volumio volumio[994]: info: ------------------------------ 6ms Feb 26 13:35:15 volumio volumio[994]: info: ------------------------------ 6ms Feb 26 13:35:15 volumio volumio[994]: info: Feb 26 13:35:15 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:15 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:15 volumio volumio[994]: info: Feb 26 13:35:15 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:15 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:15 volumio volumio[994]: error: updateQueue error: null Feb 26 13:35:15 volumio volumio[994]: info: Feb 26 13:35:15 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:15 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:15 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:35:15 volumio volumio[994]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 26 13:35:15 volumio volumio[994]: info: sendMpdCommand add "INTERNAL/!MP3/RELAX/RelaxSleep_-_Moment_Harmony_80399661.mp3" took 6 milliseconds Feb 26 13:35:15 volumio volumio[994]: info: ------------------------------ 1ms Feb 26 13:35:15 volumio volumio[994]: info: ------------------------------ 1ms Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand play Feb 26 13:35:15 volumio volumio[994]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 26 13:35:15 volumio volumio[994]: info: ------------------------------ 8ms Feb 26 13:35:15 volumio volumio[994]: info: Feb 26 13:35:15 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:15 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:15 volumio volumio[994]: info: Feb 26 13:35:15 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:15 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:15 volumio volumio[994]: info: ------------------------------ 1ms Feb 26 13:35:15 volumio volumio[994]: info: Feb 26 13:35:15 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:15 volumio volumio[994]: info: sendMpdCommand play took 9 milliseconds Feb 26 13:35:15 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:15 volumio volumio[994]: info: ------------------------------ 9ms Feb 26 13:35:15 volumio volumio[994]: info: ------------------------------ 9ms Feb 26 13:35:15 volumio volumio[994]: info: Feb 26 13:35:15 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:15 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:15 volumio volumio[994]: info: Feb 26 13:35:15 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:15 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:15 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:35:15 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:15 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:35:15 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:35:15 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:15 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Moment Harmony","artist":"RelaxSleep","album":"Релакс-Lo-Fi Для спокойных вечеров","uri":"INTERNAL/!MP3/RELAX/RelaxSleep_-_Moment_Harmony_80399661.mp3","trackType":"mp3"} Feb 26 13:35:15 volumio volumio[994]: verbose: CURRENT POSITION 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:35:15 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Moment Harmony","artist":"RelaxSleep","album":"Релакс-Lo-Fi Для спокойных вечеров","uri":"INTERNAL/!MP3/RELAX/RelaxSleep_-_Moment_Harmony_80399661.mp3","trackType":"mp3"} Feb 26 13:35:15 volumio volumio[994]: verbose: CURRENT POSITION 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:35:15 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:15 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Moment Harmony","artist":"RelaxSleep","album":"Релакс-Lo-Fi Для спокойных вечеров","uri":"INTERNAL/!MP3/RELAX/RelaxSleep_-_Moment_Harmony_80399661.mp3","trackType":"mp3"} Feb 26 13:35:15 volumio volumio[994]: verbose: CURRENT POSITION 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:35:15 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:15 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:15 volumio volumio[994]: info: ------------------------------ 4ms Feb 26 13:35:15 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:35:15 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:35:15 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Feb 26 13:35:15 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:16 volumio volumio[994]: info: CoreCommandRouter::volumioSeek Feb 26 13:35:16 volumio volumio[994]: info: CoreStateMachine::seek Feb 26 13:35:16 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:16 volumio volumio[994]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/!MP3/RELAX/RelaxSleep_-_Moment_Harmony_80399661.mp3","service":"mpd","name":"Moment Harmony","artist":"RelaxSleep","album":"Релакс-Lo-Fi Для спокойных вечеров","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=305&web=RelaxSleep/%D0%A0%D0%B5%D0%BB%D0%B0%D0%BA%D1%81-Lo-Fi%20%D0%94%D0%BB%D1%8F%20%D1%81%D0%BF%D0%BE%D0%BA%D0%BE%D0%B9%D0%BD%D1%8B%D1%85%20%D0%B2%D0%B5%D1%87%D0%B5%D1%80%D0%BE%D0%B2/extralarge&path=%2FINTERNAL%2F!MP3%2FRELAX&metadata=false","duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Feb 26 13:35:16 volumio volumio[994]: info: CoreStateMachine::startPlaybackTimer Feb 26 13:35:16 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:16 volumio volumio[994]: info: ControllerMpd::seek Feb 26 13:35:16 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:16 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:16 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:35:16 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:16 volumio volumio[994]: error: null Feb 26 13:35:16 volumio volumio[994]: info: Feb 26 13:35:16 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:16 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:16 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:16 volumio volumio[994]: info: Feb 26 13:35:16 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:16 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:16 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:16 volumio volumio[994]: info: Feb 26 13:35:16 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:16 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:16 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:16 volumio volumio[994]: info: sendMpdCommand status took 2 milliseconds Feb 26 13:35:16 volumio volumio[994]: info: sendMpdCommand status took 2 milliseconds Feb 26 13:35:16 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:35:16 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:16 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:16 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:16 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:16 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:16 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:16 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:35:16 volumio volumio[994]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 26 13:35:16 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:35:16 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:16 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:16 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:16 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:16 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:16 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:16 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63887,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Moment Harmony","artist":"RelaxSleep","album":"Релакс-Lo-Fi Для спокойных вечеров","uri":"INTERNAL/!MP3/RELAX/RelaxSleep_-_Moment_Harmony_80399661.mp3","trackType":"mp3"} Feb 26 13:35:16 volumio volumio[994]: verbose: CURRENT POSITION 5 Feb 26 13:35:16 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:16 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:35:16 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:35:16 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:16 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:16 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:35:16 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:16 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:16 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:16 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:16 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:16 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:16 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:16 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63887,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Moment Harmony","artist":"RelaxSleep","album":"Релакс-Lo-Fi Для спокойных вечеров","uri":"INTERNAL/!MP3/RELAX/RelaxSleep_-_Moment_Harmony_80399661.mp3","trackType":"mp3"} Feb 26 13:35:16 volumio volumio[994]: verbose: CURRENT POSITION 5 Feb 26 13:35:16 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:16 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:35:16 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:35:16 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:16 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:16 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:16 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:16 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:16 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:16 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:16 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:16 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:16 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63887,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Moment Harmony","artist":"RelaxSleep","album":"Релакс-Lo-Fi Для спокойных вечеров","uri":"INTERNAL/!MP3/RELAX/RelaxSleep_-_Moment_Harmony_80399661.mp3","trackType":"mp3"} Feb 26 13:35:16 volumio volumio[994]: verbose: CURRENT POSITION 5 Feb 26 13:35:16 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:16 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:35:16 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:35:16 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:16 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:16 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:16 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:16 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:16 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:16 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:35:16 volumio volumio[994]: info: ------------------------------ 8ms Feb 26 13:35:16 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:35:21 volumio volumio[994]: info: CoreCommandRouter::volumioMoveQueue Feb 26 13:35:21 volumio volumio[994]: info: CoreStateMachine::moveQueueItem 6 7 Feb 26 13:35:21 volumio volumio[994]: info: CorePlayQueue::moveQueueItem 6 --> 7 Feb 26 13:35:21 volumio volumio[994]: info: CoreCommandRouter::volumioPushQueue Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::volumioPlay Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::play index 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::stop Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::updateTrackBlock Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrackBlock Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::serviceStop Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 5 Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::serviceStop Feb 26 13:35:22 volumio volumio[994]: info: ControllerMpd::stop Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:35:22 volumio volumio[994]: info: Feb 26 13:35:22 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:22 volumio volumio[994]: info: sendMpdCommand stop took 1 milliseconds Feb 26 13:35:22 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::play index undefined Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::startPlaybackTimer Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/!MP3/RELAX/08-Childhood Hour.mp3 Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:35:22 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:35:22 volumio volumio[994]: info: sendMpdCommand stop took 1 milliseconds Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 13:35:22 volumio volumio[994]: info: Feb 26 13:35:22 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:22 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:22 volumio volumio[994]: info: Feb 26 13:35:22 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:22 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:22 volumio volumio[994]: info: Feb 26 13:35:22 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:22 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:22 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:35:22 volumio volumio[994]: info: sendMpdCommand clear took 1 milliseconds Feb 26 13:35:22 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:35:22 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/!MP3/RELAX/08-Childhood Hour.mp3" Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:22 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: 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":"Moment Harmony","artist":"RelaxSleep","album":"Релакс-Lo-Fi Для спокойных вечеров","uri":"INTERNAL/!MP3/RELAX/RelaxSleep_-_Moment_Harmony_80399661.mp3","trackType":"mp3"} Feb 26 13:35:22 volumio volumio[994]: verbose: CURRENT POSITION 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:22 volumio volumio[994]: info: No code Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:22 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:35:22 volumio volumio[994]: verbose: CURRENT POSITION 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:22 volumio volumio[994]: info: No code Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:22 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:35:22 volumio volumio[994]: verbose: CURRENT POSITION 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:22 volumio volumio[994]: info: No code Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:22 volumio volumio[994]: info: ------------------------------ 10ms Feb 26 13:35:22 volumio volumio[994]: info: ------------------------------ 9ms Feb 26 13:35:22 volumio volumio[994]: info: Feb 26 13:35:22 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:22 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:22 volumio volumio[994]: info: Feb 26 13:35:22 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:22 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:22 volumio volumio[994]: error: updateQueue error: null Feb 26 13:35:22 volumio volumio[994]: info: Feb 26 13:35:22 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:22 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:22 volumio volumio[994]: info: ------------------------------ 9ms Feb 26 13:35:22 volumio volumio[994]: info: sendMpdCommand add "INTERNAL/!MP3/RELAX/08-Childhood Hour.mp3" took 9 milliseconds Feb 26 13:35:22 volumio volumio[994]: info: ------------------------------ 0ms Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand play Feb 26 13:35:22 volumio volumio[994]: info: Feb 26 13:35:22 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:22 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:22 volumio volumio[994]: info: Feb 26 13:35:22 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:22 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:22 volumio volumio[994]: info: ------------------------------ 3ms Feb 26 13:35:22 volumio volumio[994]: info: Feb 26 13:35:22 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:22 volumio volumio[994]: info: sendMpdCommand play took 9 milliseconds Feb 26 13:35:22 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:22 volumio volumio[994]: info: ------------------------------ 9ms Feb 26 13:35:22 volumio volumio[994]: info: ------------------------------ 8ms Feb 26 13:35:22 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:22 volumio volumio[994]: info: Feb 26 13:35:22 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:22 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:22 volumio volumio[994]: info: Feb 26 13:35:22 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:22 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:22 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:35:22 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:35:22 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:22 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":273,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Childhood hour","artist":"Bernward Koch","album":"Walking through clouds","uri":"INTERNAL/!MP3/RELAX/08-Childhood Hour.mp3","trackType":"mp3"} Feb 26 13:35:22 volumio volumio[994]: verbose: CURRENT POSITION 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:35:22 volumio volumio[994]: info: ------------------------------ 3ms Feb 26 13:35:22 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:35:22 volumio volumio[994]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:22 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:22 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":273,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Childhood hour","artist":"Bernward Koch","album":"Walking through clouds","uri":"INTERNAL/!MP3/RELAX/08-Childhood Hour.mp3","trackType":"mp3"} Feb 26 13:35:22 volumio volumio[994]: verbose: CURRENT POSITION 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:35:22 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:22 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":273,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Childhood hour","artist":"Bernward Koch","album":"Walking through clouds","uri":"INTERNAL/!MP3/RELAX/08-Childhood Hour.mp3","trackType":"mp3"} Feb 26 13:35:22 volumio volumio[994]: verbose: CURRENT POSITION 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:35:22 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:22 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:22 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:22 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:22 volumio volumio[994]: info: ------------------------------ 5ms Feb 26 13:35:22 volumio volumio[994]: info: ------------------------------ 4ms Feb 26 13:35:25 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Feb 26 13:35:25 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:25 volumio volumio[994]: info: Listing playlists Feb 26 13:35:25 volumio volumio[994]: info: Listing playlists Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::volumioPlay Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::play index 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::stop Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::updateTrackBlock Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrackBlock Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::serviceStop Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 6 Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::serviceStop Feb 26 13:35:27 volumio volumio[994]: info: ControllerMpd::stop Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:35:27 volumio volumio[994]: info: sendMpdCommand stop took 1 milliseconds Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::play index undefined Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::startPlaybackTimer Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/!MP3/RELAX/01- DJ Creepy_ Deep House - Cloud.mp3 Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 13:35:27 volumio volumio[994]: info: Feb 26 13:35:27 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:27 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:27 volumio volumio[994]: info: sendMpdCommand stop took 0 milliseconds Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 13:35:27 volumio volumio[994]: info: Feb 26 13:35:27 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:27 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:27 volumio volumio[994]: info: Feb 26 13:35:27 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:27 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:27 volumio volumio[994]: info: Feb 26 13:35:27 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:27 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:27 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:35:27 volumio volumio[994]: info: sendMpdCommand clear took 1 milliseconds Feb 26 13:35:27 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:35:27 volumio volumio[994]: info: sendMpdCommand status took 0 milliseconds Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/!MP3/RELAX/01- DJ Creepy_ Deep House - Cloud.mp3" Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:27 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:35:27 volumio volumio[994]: verbose: CURRENT POSITION 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:27 volumio volumio[994]: info: No code Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:27 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 26 13:35:27 volumio volumio[994]: verbose: CURRENT POSITION 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::syncState stateService stop Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:27 volumio volumio[994]: info: No code Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:27 volumio volumio[994]: info: ------------------------------ 8ms Feb 26 13:35:27 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:35:27 volumio volumio[994]: info: Feb 26 13:35:27 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:27 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:27 volumio volumio[994]: info: Feb 26 13:35:27 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:27 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:27 volumio volumio[994]: error: updateQueue error: null Feb 26 13:35:27 volumio volumio[994]: info: Feb 26 13:35:27 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:27 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:27 volumio volumio[994]: info: ------------------------------ 9ms Feb 26 13:35:27 volumio volumio[994]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 26 13:35:27 volumio volumio[994]: info: sendMpdCommand add "INTERNAL/!MP3/RELAX/01- DJ Creepy_ Deep House - Cloud.mp3" took 9 milliseconds Feb 26 13:35:27 volumio volumio[994]: info: ------------------------------ 1ms Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand play Feb 26 13:35:27 volumio volumio[994]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 26 13:35:27 volumio volumio[994]: info: ------------------------------ 10ms Feb 26 13:35:27 volumio volumio[994]: info: Feb 26 13:35:27 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:27 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:27 volumio volumio[994]: info: Feb 26 13:35:27 volumio volumio[994]: ---------------------------- MPD announces system playlist update Feb 26 13:35:27 volumio volumio[994]: info: Ignoring MPD Status Update Feb 26 13:35:27 volumio volumio[994]: info: Feb 26 13:35:27 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:27 volumio volumio[994]: info: ------------------------------ 6ms Feb 26 13:35:27 volumio volumio[994]: info: sendMpdCommand play took 5 milliseconds Feb 26 13:35:27 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:27 volumio volumio[994]: info: ------------------------------ 5ms Feb 26 13:35:27 volumio volumio[994]: info: Feb 26 13:35:27 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:27 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:27 volumio volumio[994]: info: Feb 26 13:35:27 volumio volumio[994]: ---------------------------- MPD announces state update: player Feb 26 13:35:27 volumio volumio[994]: info: ControllerMpd::getState Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand status Feb 26 13:35:27 volumio volumio[994]: info: sendMpdCommand status took 1 milliseconds Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::parseState Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 13:35:27 volumio volumio[994]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 13:35:27 volumio volumio[994]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 13:35:27 volumio volumio[994]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:27 volumio volumio[994]: verbose: ControllerMpd::parseTrackInfo Feb 26 13:35:27 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":196,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01- DJ Creepy_ Deep House - Cloud.mp3","artist":null,"album":null,"uri":"INTERNAL/!MP3/RELAX/01- DJ Creepy_ Deep House - Cloud.mp3","trackType":"mp3"} Feb 26 13:35:27 volumio volumio[994]: verbose: CURRENT POSITION 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Feb 26 13:35:27 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":196,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01- DJ Creepy_ Deep House - Cloud.mp3","artist":null,"album":null,"uri":"INTERNAL/!MP3/RELAX/01- DJ Creepy_ Deep House - Cloud.mp3","trackType":"mp3"} Feb 26 13:35:27 volumio volumio[994]: verbose: CURRENT POSITION 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:35:27 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:27 volumio volumio[994]: info: ControllerMpd::pushState Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::servicePushState Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":196,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01- DJ Creepy_ Deep House - Cloud.mp3","artist":null,"album":null,"uri":"INTERNAL/!MP3/RELAX/01- DJ Creepy_ Deep House - Cloud.mp3","trackType":"mp3"} Feb 26 13:35:27 volumio volumio[994]: verbose: CURRENT POSITION 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Feb 26 13:35:27 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:27 volumio volumio[994]: info: CoreStateMachine::pushState Feb 26 13:35:27 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Feb 26 13:35:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Feb 26 13:35:27 volumio volumio[994]: info: ------------------------------ 6ms Feb 26 13:35:27 volumio volumio[994]: info: ------------------------------ 7ms Feb 26 13:35:27 volumio volumio[994]: info: ------------------------------ 8ms Feb 26 13:35:28 volumio volumio[994]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 13:35:28 volumio volumio[994]: Error: connect ETIMEDOUT 23.73.2.84:443 Feb 26 13:35:28 volumio volumio[994]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 26 13:35:28 volumio volumio[994]: errno: -110, Feb 26 13:35:28 volumio volumio[994]: code: 'ETIMEDOUT', Feb 26 13:35:28 volumio volumio[994]: syscall: 'connect', Feb 26 13:35:28 volumio volumio[994]: address: '23.73.2.84', Feb 26 13:35:28 volumio volumio[994]: port: 443 Feb 26 13:35:28 volumio volumio[994]: } Feb 26 13:35:28 volumio volumio[994]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 13:35:28 volumio sudo[16595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-26 13:34 Feb 26 13:35:28 volumio sudo[16595]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:18:30 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="33f9369b544b4488ff4979d2ee3813a5"