-- Logs begin at Tue 2025-01-21 16:36:57 GMT, end at Tue 2025-01-21 16:40:49 GMT. -- Jan 21 16:39:10 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::ClearQueue Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::stop Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::stPlaybackTimer Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::updateTrackBlock Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrackBlock Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::serviceStop Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::serviceStop Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::stop Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand stop Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::clearPlayQueue Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::saveQueue Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushQueue Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::addQueueItems Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::addQueueItems Jan 21 16:39:10 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:39:10 volumio volumio[1295]: info: Adding Item to queue: artists://Afx Jan 21 16:39:10 volumio volumio[1295]: info: Using cached record of: artists://Afx Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushQueue Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::saveQueue Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::updateTrackBlock Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrackBlock Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPlay Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::play index 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::stop Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::play index undefined Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::startPlaybackTimer Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3 Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand stop Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand stop took 9 milliseconds Jan 21 16:39:10 volumio volumio[1295]: info: Jan 21 16:39:10 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand stop took 3 milliseconds Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand clear Jan 21 16:39:10 volumio volumio[1295]: info: Jan 21 16:39:10 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:10 volumio volumio[1295]: info: Jan 21 16:39:10 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:10 volumio volumio[1295]: info: Jan 21 16:39:10 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:10 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand status took 2 milliseconds Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand clear took 2 milliseconds Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3" Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 21 16:39:10 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::syncState stateService stop Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus stop Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: No code Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 21 16:39:10 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::syncState stateService stop Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus stop Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: No code Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: ------------------------------ 14ms Jan 21 16:39:10 volumio volumio[1295]: info: ------------------------------ 15ms Jan 21 16:39:10 volumio volumio[1295]: info: Jan 21 16:39:10 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:10 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:10 volumio volumio[1295]: info: Jan 21 16:39:10 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:10 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:10 volumio volumio[1295]: error: updateQueue error: null Jan 21 16:39:10 volumio volumio[1295]: info: Jan 21 16:39:10 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:10 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:10 volumio volumio[1295]: info: ------------------------------ 16ms Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand add "NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3" took 15 milliseconds Jan 21 16:39:10 volumio volumio[1295]: info: ------------------------------ 1ms Jan 21 16:39:10 volumio volumio[1295]: info: ------------------------------ 1ms Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand play Jan 21 16:39:10 volumio volumio[1295]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 21 16:39:10 volumio volumio[1295]: info: ------------------------------ 18ms Jan 21 16:39:10 volumio volumio[1295]: info: Jan 21 16:39:10 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:10 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:10 volumio volumio[1295]: info: Jan 21 16:39:10 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:10 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:10 volumio volumio[1295]: info: ------------------------------ 3ms Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand play took 2 milliseconds Jan 21 16:39:10 volumio volumio[1295]: info: ------------------------------ 2ms Jan 21 16:39:10 volumio volumio[1295]: info: ------------------------------ 2ms Jan 21 16:39:10 volumio volumio[1295]: info: Jan 21 16:39:10 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:10 volumio volumio[1295]: info: Jan 21 16:39:10 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:10 volumio volumio[1295]: info: Jan 21 16:39:10 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:10 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jan 21 16:39:10 volumio volumio[1295]: info: Jan 21 16:39:10 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand status took 13 milliseconds Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand status took 14 milliseconds Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand status took 13 milliseconds Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":118,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bonus High Frequencies","artist":"Afx","album":"Afx - 2 Remixes By Afx","uri":"NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3","trackType":"mp3"} Jan 21 16:39:10 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus stop Jan 21 16:39:10 volumio volumio[1295]: info: ------------------------------ 16ms Jan 21 16:39:10 volumio volumio[1295]: info: Jan 21 16:39:10 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:10 volumio volumio[1295]: info: Jan 21 16:39:10 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand status took 0 milliseconds Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":365,"duration":118,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bonus High Frequencies","artist":"Afx","album":"Afx - 2 Remixes By Afx","uri":"NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3","trackType":"mp3"} Jan 21 16:39:10 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:39:10 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":411,"duration":118,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bonus High Frequencies","artist":"Afx","album":"Afx - 2 Remixes By Afx","uri":"NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3","trackType":"mp3"} Jan 21 16:39:10 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:39:10 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":118,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bonus High Frequencies","artist":"Afx","album":"Afx - 2 Remixes By Afx","uri":"NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3","trackType":"mp3"} Jan 21 16:39:10 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:39:10 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: ------------------------------ 33ms Jan 21 16:39:10 volumio volumio[1295]: info: ------------------------------ 32ms Jan 21 16:39:10 volumio volumio[1295]: info: ------------------------------ 20ms Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 21 16:39:10 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:10 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":118,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bonus High Frequencies","artist":"Afx","album":"Afx - 2 Remixes By Afx","uri":"NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3","trackType":"mp3"} Jan 21 16:39:10 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:39:10 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":118,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bonus High Frequencies","artist":"Afx","album":"Afx - 2 Remixes By Afx","uri":"NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3","trackType":"mp3"} Jan 21 16:39:10 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:39:10 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:10 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:10 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:10 volumio volumio[1295]: info: ------------------------------ 28ms Jan 21 16:39:10 volumio volumio[1295]: info: ------------------------------ 28ms Jan 21 16:39:15 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::ClearQueue Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::stop Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::stPlaybackTimer Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::updateTrackBlock Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrackBlock Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::serviceStop Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::serviceStop Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::stop Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand stop Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::clearPlayQueue Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::saveQueue Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushQueue Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::addQueueItems Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::addQueueItems Jan 21 16:39:15 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:39:15 volumio volumio[1295]: info: Adding Item to queue: artists://Afx/Afx%20-%202%20Remixes%20By%20Afx Jan 21 16:39:15 volumio volumio[1295]: info: Exploding uri artists://Afx/Afx%20-%202%20Remixes%20By%20Afx in service mpd Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushQueue Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::saveQueue Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::updateTrackBlock Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrackBlock Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPlay Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::play index 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::stop Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::play index undefined Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::startPlaybackTimer Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3 Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand stop Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand stop took 9 milliseconds Jan 21 16:39:15 volumio volumio[1295]: info: Jan 21 16:39:15 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand stop took 1 milliseconds Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand clear Jan 21 16:39:15 volumio volumio[1295]: info: Jan 21 16:39:15 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:15 volumio volumio[1295]: info: Jan 21 16:39:15 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:15 volumio volumio[1295]: info: Jan 21 16:39:15 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:15 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand status took 2 milliseconds Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand clear took 2 milliseconds Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3" Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 21 16:39:15 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::syncState stateService stop Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus stop Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: No code Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 21 16:39:15 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::syncState stateService stop Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus stop Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: No code Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: ------------------------------ 15ms Jan 21 16:39:15 volumio volumio[1295]: info: ------------------------------ 15ms Jan 21 16:39:15 volumio volumio[1295]: info: Jan 21 16:39:15 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:15 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:15 volumio volumio[1295]: error: updateQueue error: null Jan 21 16:39:15 volumio volumio[1295]: info: Jan 21 16:39:15 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:15 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:15 volumio volumio[1295]: info: ------------------------------ 15ms Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand add "NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3" took 15 milliseconds Jan 21 16:39:15 volumio volumio[1295]: info: ------------------------------ 2ms Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand play Jan 21 16:39:15 volumio volumio[1295]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 21 16:39:15 volumio volumio[1295]: info: ------------------------------ 18ms Jan 21 16:39:15 volumio volumio[1295]: info: Jan 21 16:39:15 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:15 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:15 volumio volumio[1295]: info: Jan 21 16:39:15 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:15 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:15 volumio volumio[1295]: info: ------------------------------ 3ms Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand play took 2 milliseconds Jan 21 16:39:15 volumio volumio[1295]: info: ------------------------------ 2ms Jan 21 16:39:15 volumio volumio[1295]: info: Jan 21 16:39:15 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:15 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:15 volumio volumio[1295]: info: ------------------------------ 0ms Jan 21 16:39:15 volumio volumio[1295]: info: Jan 21 16:39:15 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:15 volumio volumio[1295]: info: Jan 21 16:39:15 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:15 volumio volumio[1295]: info: Jan 21 16:39:15 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:15 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jan 21 16:39:15 volumio volumio[1295]: info: Jan 21 16:39:15 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand status took 17 milliseconds Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand status took 17 milliseconds Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand status took 16 milliseconds Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:15 volumio volumio[1295]: info: Jan 21 16:39:15 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:15 volumio volumio[1295]: info: Jan 21 16:39:15 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand status took 4 milliseconds Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":118,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bonus High Frequencies","artist":"Afx","album":"Afx - 2 Remixes By Afx","uri":"NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3","trackType":"mp3"} Jan 21 16:39:15 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus stop Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":118,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bonus High Frequencies","artist":"Afx","album":"Afx - 2 Remixes By Afx","uri":"NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3","trackType":"mp3"} Jan 21 16:39:15 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:39:15 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57,"duration":118,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bonus High Frequencies","artist":"Afx","album":"Afx - 2 Remixes By Afx","uri":"NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3","trackType":"mp3"} Jan 21 16:39:15 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:39:15 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: ------------------------------ 27ms Jan 21 16:39:15 volumio volumio[1295]: info: ------------------------------ 34ms Jan 21 16:39:15 volumio volumio[1295]: info: ------------------------------ 34ms Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand status took 14 milliseconds Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":118,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bonus High Frequencies","artist":"Afx","album":"Afx - 2 Remixes By Afx","uri":"NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3","trackType":"mp3"} Jan 21 16:39:15 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:39:15 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":118,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bonus High Frequencies","artist":"Afx","album":"Afx - 2 Remixes By Afx","uri":"NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3","trackType":"mp3"} Jan 21 16:39:15 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:39:15 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: ------------------------------ 27ms Jan 21 16:39:15 volumio volumio[1295]: info: ------------------------------ 23ms Jan 21 16:39:15 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 21 16:39:15 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:15 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":118,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bonus High Frequencies","artist":"Afx","album":"Afx - 2 Remixes By Afx","uri":"NAS/Music/Aphex Twin/Remix/03 Afx - Bonus High Frequencies.mp3","trackType":"mp3"} Jan 21 16:39:15 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:39:15 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:15 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:15 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:15 volumio volumio[1295]: info: ------------------------------ 27ms Jan 21 16:39:18 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 21 16:39:18 volumio volumio[1295]: info: CURURI: artists:// Jan 21 16:39:18 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:39:20 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 21 16:39:20 volumio volumio[1295]: info: CURURI: artists://Amy%20Winehouse Jan 21 16:39:20 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:39:20 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:39:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Amy Winehouse/Back to Black/01 - Rehab.mp3 Jan 21 16:39:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Amy Winehouse/Back to Black/02 - You Know I'm No Good.mp3 Jan 21 16:39:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Amy Winehouse/Back to Black/03 - Me And Mr Jones (Fuckery).mp3 Jan 21 16:39:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Amy Winehouse/Back to Black/04 - Just Friends.mp3 Jan 21 16:39:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Amy Winehouse/Back to Black/05 - Back To Black.mp3 Jan 21 16:39:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Amy Winehouse/Back to Black/06 - Love Is A Losing Game.mp3 Jan 21 16:39:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Amy Winehouse/Back to Black/07 - Tears Dry On Their Own.mp3 Jan 21 16:39:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Amy Winehouse/Back to Black/08 - Wake Up Alone.mp3 Jan 21 16:39:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Amy Winehouse/Back to Black/09 - Some Unholy War.mp3 Jan 21 16:39:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Amy Winehouse/Back to Black/10 - He Can Only Hold Her.mp3 Jan 21 16:39:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Amy Winehouse/Back to Black/11 - Addicted.mp3 Jan 21 16:39:20 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Amy Winehouse/Back to Black/01 - Rehab.mp3 in service mpd Jan 21 16:39:20 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Amy%20Winehouse/Back%20To%20Black/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAmy%20Winehouse%2FBack%20to%20Black%2F01%20-%20Rehab.mp3&metadata=false Jan 21 16:39:20 volumio volumio[1295]: info: URI /mnt/NAS/Music/Amy Winehouse/Back to Black/01 - Rehab.mp3 Jan 21 16:39:20 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Amy Winehouse/Back to Black/02 - You Know I'm No Good.mp3 in service mpd Jan 21 16:39:20 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Amy%20Winehouse/Back%20To%20Black/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAmy%20Winehouse%2FBack%20to%20Black%2F02%20-%20You%20Know%20I'm%20No%20Good.mp3&metadata=false Jan 21 16:39:20 volumio volumio[1295]: info: URI /mnt/NAS/Music/Amy Winehouse/Back to Black/02 - You Know I'm No Good.mp3 Jan 21 16:39:20 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Amy Winehouse/Back to Black/03 - Me And Mr Jones (Fuckery).mp3 in service mpd Jan 21 16:39:20 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Amy%20Winehouse/Back%20To%20Black/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAmy%20Winehouse%2FBack%20to%20Black%2F03%20-%20Me%20And%20Mr%20Jones%20(Fuckery).mp3&metadata=false Jan 21 16:39:20 volumio volumio[1295]: info: URI /mnt/NAS/Music/Amy Winehouse/Back to Black/03 - Me And Mr Jones (Fuckery).mp3 Jan 21 16:39:20 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Amy Winehouse/Back to Black/04 - Just Friends.mp3 in service mpd Jan 21 16:39:20 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Amy%20Winehouse/Back%20To%20Black/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAmy%20Winehouse%2FBack%20to%20Black%2F04%20-%20Just%20Friends.mp3&metadata=false Jan 21 16:39:20 volumio volumio[1295]: info: URI /mnt/NAS/Music/Amy Winehouse/Back to Black/04 - Just Friends.mp3 Jan 21 16:39:20 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Amy Winehouse/Back to Black/05 - Back To Black.mp3 in service mpd Jan 21 16:39:20 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Amy%20Winehouse/Back%20To%20Black/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAmy%20Winehouse%2FBack%20to%20Black%2F05%20-%20Back%20To%20Black.mp3&metadata=false Jan 21 16:39:20 volumio volumio[1295]: info: URI /mnt/NAS/Music/Amy Winehouse/Back to Black/05 - Back To Black.mp3 Jan 21 16:39:21 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Amy Winehouse/Back to Black/06 - Love Is A Losing Game.mp3 in service mpd Jan 21 16:39:21 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Amy%20Winehouse/Back%20To%20Black/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAmy%20Winehouse%2FBack%20to%20Black%2F06%20-%20Love%20Is%20A%20Losing%20Game.mp3&metadata=false Jan 21 16:39:21 volumio volumio[1295]: info: URI /mnt/NAS/Music/Amy Winehouse/Back to Black/06 - Love Is A Losing Game.mp3 Jan 21 16:39:21 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Amy Winehouse/Back to Black/07 - Tears Dry On Their Own.mp3 in service mpd Jan 21 16:39:21 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Amy%20Winehouse/Back%20To%20Black/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAmy%20Winehouse%2FBack%20to%20Black%2F07%20-%20Tears%20Dry%20On%20Their%20Own.mp3&metadata=false Jan 21 16:39:21 volumio volumio[1295]: info: URI /mnt/NAS/Music/Amy Winehouse/Back to Black/07 - Tears Dry On Their Own.mp3 Jan 21 16:39:21 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Amy Winehouse/Back to Black/08 - Wake Up Alone.mp3 in service mpd Jan 21 16:39:21 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Amy%20Winehouse/Back%20To%20Black/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAmy%20Winehouse%2FBack%20to%20Black%2F08%20-%20Wake%20Up%20Alone.mp3&metadata=false Jan 21 16:39:21 volumio volumio[1295]: info: URI /mnt/NAS/Music/Amy Winehouse/Back to Black/08 - Wake Up Alone.mp3 Jan 21 16:39:21 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Amy Winehouse/Back to Black/09 - Some Unholy War.mp3 in service mpd Jan 21 16:39:21 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Amy%20Winehouse/Back%20To%20Black/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAmy%20Winehouse%2FBack%20to%20Black%2F09%20-%20Some%20Unholy%20War.mp3&metadata=false Jan 21 16:39:21 volumio volumio[1295]: info: URI /mnt/NAS/Music/Amy Winehouse/Back to Black/09 - Some Unholy War.mp3 Jan 21 16:39:21 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Amy Winehouse/Back to Black/10 - He Can Only Hold Her.mp3 in service mpd Jan 21 16:39:21 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Amy%20Winehouse/Back%20To%20Black/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAmy%20Winehouse%2FBack%20to%20Black%2F10%20-%20He%20Can%20Only%20Hold%20Her.mp3&metadata=false Jan 21 16:39:21 volumio volumio[1295]: info: URI /mnt/NAS/Music/Amy Winehouse/Back to Black/10 - He Can Only Hold Her.mp3 Jan 21 16:39:21 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Amy Winehouse/Back to Black/11 - Addicted.mp3 in service mpd Jan 21 16:39:21 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Amy%20Winehouse/Back%20To%20Black/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAmy%20Winehouse%2FBack%20to%20Black%2F11%20-%20Addicted.mp3&metadata=false Jan 21 16:39:21 volumio volumio[1295]: info: URI /mnt/NAS/Music/Amy Winehouse/Back to Black/11 - Addicted.mp3 Jan 21 16:39:22 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::ClearQueue Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::stop Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::stPlaybackTimer Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::updateTrackBlock Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrackBlock Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::serviceStop Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::serviceStop Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::stop Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand stop Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::clearPlayQueue Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::saveQueue Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushQueue Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::addQueueItems Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::addQueueItems Jan 21 16:39:22 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:39:22 volumio volumio[1295]: info: Adding Item to queue: artists://Amy%20Winehouse/Back%20To%20Black Jan 21 16:39:22 volumio volumio[1295]: info: Exploding uri artists://Amy%20Winehouse/Back%20To%20Black in service mpd Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushQueue Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::saveQueue Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::updateTrackBlock Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrackBlock Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPlay Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::play index 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::stop Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::play index undefined Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::startPlaybackTimer Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Amy Winehouse/Back to Black/01 - Rehab.mp3 Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand stop Jan 21 16:39:22 volumio volumio[1295]: info: Jan 21 16:39:22 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand stop took 13 milliseconds Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand stop took 2 milliseconds Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand clear Jan 21 16:39:22 volumio volumio[1295]: info: Jan 21 16:39:22 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:22 volumio volumio[1295]: info: Jan 21 16:39:22 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:22 volumio volumio[1295]: info: Jan 21 16:39:22 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:22 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand status took 4 milliseconds Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand clear took 4 milliseconds Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Amy Winehouse/Back to Black/01 - Rehab.mp3" Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 21 16:39:22 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::syncState stateService stop Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus stop Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: No code Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 21 16:39:22 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::syncState stateService stop Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus stop Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: No code Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: ------------------------------ 18ms Jan 21 16:39:22 volumio volumio[1295]: info: ------------------------------ 17ms Jan 21 16:39:22 volumio volumio[1295]: info: Jan 21 16:39:22 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:22 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:22 volumio volumio[1295]: error: updateQueue error: null Jan 21 16:39:22 volumio volumio[1295]: info: Jan 21 16:39:22 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:22 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:22 volumio volumio[1295]: info: ------------------------------ 20ms Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand add "NAS/Music/Amy Winehouse/Back to Black/01 - Rehab.mp3" took 19 milliseconds Jan 21 16:39:22 volumio volumio[1295]: info: ------------------------------ 3ms Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand play Jan 21 16:39:22 volumio volumio[1295]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 21 16:39:22 volumio volumio[1295]: info: ------------------------------ 27ms Jan 21 16:39:22 volumio volumio[1295]: info: Jan 21 16:39:22 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:22 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:22 volumio volumio[1295]: info: Jan 21 16:39:22 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:22 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:22 volumio volumio[1295]: info: ------------------------------ 6ms Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand play took 5 milliseconds Jan 21 16:39:22 volumio volumio[1295]: info: ------------------------------ 5ms Jan 21 16:39:22 volumio volumio[1295]: info: ------------------------------ 4ms Jan 21 16:39:22 volumio volumio[1295]: info: Jan 21 16:39:22 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:39:22 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:39:22 volumio volumio[1295]: info: ------------------------------ 1ms Jan 21 16:39:22 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jan 21 16:39:22 volumio volumio[1295]: info: Jan 21 16:39:22 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:22 volumio volumio[1295]: info: Jan 21 16:39:22 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:22 volumio volumio[1295]: info: Jan 21 16:39:22 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand status took 15 milliseconds Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:22 volumio volumio[1295]: info: Jan 21 16:39:22 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand status took 17 milliseconds Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand status took 16 milliseconds Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":215,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Rehab","artist":"Amy Winehouse","album":"Back To Black","uri":"NAS/Music/Amy Winehouse/Back to Black/01 - Rehab.mp3","trackType":"mp3"} Jan 21 16:39:22 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus stop Jan 21 16:39:22 volumio volumio[1295]: info: ------------------------------ 20ms Jan 21 16:39:22 volumio volumio[1295]: info: Jan 21 16:39:22 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:22 volumio volumio[1295]: info: Jan 21 16:39:22 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand status took 3 milliseconds Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":215,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Rehab","artist":"Amy Winehouse","album":"Back To Black","uri":"NAS/Music/Amy Winehouse/Back to Black/01 - Rehab.mp3","trackType":"mp3"} Jan 21 16:39:22 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:39:22 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":215,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Rehab","artist":"Amy Winehouse","album":"Back To Black","uri":"NAS/Music/Amy Winehouse/Back to Black/01 - Rehab.mp3","trackType":"mp3"} Jan 21 16:39:22 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:39:22 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: ------------------------------ 32ms Jan 21 16:39:22 volumio volumio[1295]: info: ------------------------------ 33ms Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 21 16:39:22 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:22 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":215,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Rehab","artist":"Amy Winehouse","album":"Back To Black","uri":"NAS/Music/Amy Winehouse/Back to Black/01 - Rehab.mp3","trackType":"mp3"} Jan 21 16:39:22 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:39:22 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":215,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Rehab","artist":"Amy Winehouse","album":"Back To Black","uri":"NAS/Music/Amy Winehouse/Back to Black/01 - Rehab.mp3","trackType":"mp3"} Jan 21 16:39:22 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:39:22 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":215,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Rehab","artist":"Amy Winehouse","album":"Back To Black","uri":"NAS/Music/Amy Winehouse/Back to Black/01 - Rehab.mp3","trackType":"mp3"} Jan 21 16:39:22 volumio volumio[1295]: verbose: CURRENT POSITION 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:39:22 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:39:22 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:39:22 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:39:22 volumio volumio[1295]: info: ------------------------------ 33ms Jan 21 16:39:22 volumio volumio[1295]: info: ------------------------------ 32ms Jan 21 16:39:22 volumio volumio[1295]: info: ------------------------------ 33ms Jan 21 16:40:07 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 21 16:40:07 volumio volumio[1295]: info: CURURI: artists:// Jan 21 16:40:07 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:40:19 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 21 16:40:19 volumio volumio[1295]: info: CURURI: artists://blink-182 Jan 21 16:40:20 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:40:20 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Enema of the State/01 - Dumpweed.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Enema of the State/02 - Don't Leave Me.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Enema of the State/03 - Aliens Exist.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Enema of the State/04 - Going Away To College.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Enema of the State/05 - What's My Age Again-.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Enema of the State/06 - Dysentery Gary.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Enema of the State/07 - Adam's Song.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Enema of the State/08 - All The Small Things.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Enema of the State/09 - The Party Song.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Enema of the State/10 - Mutt.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Enema of the State/11 - Wendy Clear.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Enema of the State/12 - Anthem.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Neighborhoods/01 Ghost on the Dance Floor.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Neighborhoods/02 Natives.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Neighborhoods/03 Up All Night.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Neighborhoods/04 After Midnight.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Neighborhoods/05 Heart's All Gone.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Neighborhoods/06 Wishing Well.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Neighborhoods/07 Kaleidoscope.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Neighborhoods/08 This Is Home.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Neighborhoods/09 MH 4.18.2011.mp3 Jan 21 16:40:20 volumio volumio[1295]: info: Preloading song: music-library/NAS/Music/Blink-182/Neighborhoods/10 Love Is Dangerous.mp3 Jan 21 16:40:21 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Enema of the State/01 - Dumpweed.mp3 in service mpd Jan 21 16:40:21 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Enema of the State/02 - Don't Leave Me.mp3 in service mpd Jan 21 16:40:21 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Enema of the State/03 - Aliens Exist.mp3 in service mpd Jan 21 16:40:21 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Enema of the State/04 - Going Away To College.mp3 in service mpd Jan 21 16:40:21 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Enema of the State/05 - What's My Age Again-.mp3 in service mpd Jan 21 16:40:21 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Enema of the State/06 - Dysentery Gary.mp3 in service mpd Jan 21 16:40:21 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Enema of the State/07 - Adam's Song.mp3 in service mpd Jan 21 16:40:21 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Enema of the State/08 - All The Small Things.mp3 in service mpd Jan 21 16:40:21 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Enema of the State/09 - The Party Song.mp3 in service mpd Jan 21 16:40:21 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Enema of the State/10 - Mutt.mp3 in service mpd Jan 21 16:40:22 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Enema of the State/11 - Wendy Clear.mp3 in service mpd Jan 21 16:40:22 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Enema of the State/12 - Anthem.mp3 in service mpd Jan 21 16:40:22 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Neighborhoods/01 Ghost on the Dance Floor.mp3 in service mpd Jan 21 16:40:22 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Neighborhoods/02 Natives.mp3 in service mpd Jan 21 16:40:22 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Neighborhoods/03 Up All Night.mp3 in service mpd Jan 21 16:40:22 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Neighborhoods/04 After Midnight.mp3 in service mpd Jan 21 16:40:22 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Neighborhoods/05 Heart's All Gone.mp3 in service mpd Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Blink-182/Enema%20of%20the%20State/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FEnema%20of%20the%20State%2F01%20-%20Dumpweed.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Enema of the State/01 - Dumpweed.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Blink-182/Enema%20of%20the%20State/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FEnema%20of%20the%20State%2F02%20-%20Don't%20Leave%20Me.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Enema of the State/02 - Don't Leave Me.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Blink-182/Enema%20of%20the%20State/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FEnema%20of%20the%20State%2F03%20-%20Aliens%20Exist.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Enema of the State/03 - Aliens Exist.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Blink-182/Enema%20of%20the%20State/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FEnema%20of%20the%20State%2F04%20-%20Going%20Away%20To%20College.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Enema of the State/04 - Going Away To College.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Blink-182/Enema%20of%20the%20State/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FEnema%20of%20the%20State%2F05%20-%20What's%20My%20Age%20Again-.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Enema of the State/05 - What's My Age Again-.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Blink-182/Enema%20of%20the%20State/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FEnema%20of%20the%20State%2F06%20-%20Dysentery%20Gary.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Enema of the State/06 - Dysentery Gary.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Blink-182/Enema%20of%20the%20State/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FEnema%20of%20the%20State%2F07%20-%20Adam's%20Song.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Enema of the State/07 - Adam's Song.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Blink-182/Enema%20of%20the%20State/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FEnema%20of%20the%20State%2F08%20-%20All%20The%20Small%20Things.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Enema of the State/08 - All The Small Things.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Blink-182/Enema%20of%20the%20State/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FEnema%20of%20the%20State%2F09%20-%20The%20Party%20Song.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Enema of the State/09 - The Party Song.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Blink-182/Enema%20of%20the%20State/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FEnema%20of%20the%20State%2F10%20-%20Mutt.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Enema of the State/10 - Mutt.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Blink-182/Enema%20of%20the%20State/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FEnema%20of%20the%20State%2F11%20-%20Wendy%20Clear.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Enema of the State/11 - Wendy Clear.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=Blink-182/Enema%20of%20the%20State/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FEnema%20of%20the%20State%2F12%20-%20Anthem.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Enema of the State/12 - Anthem.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=blink-182/Neighborhoods/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FNeighborhoods%2F01%20Ghost%20on%20the%20Dance%20Floor.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Neighborhoods/01 Ghost on the Dance Floor.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=blink-182/Neighborhoods/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FNeighborhoods%2F02%20Natives.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Neighborhoods/02 Natives.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=blink-182/Neighborhoods/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FNeighborhoods%2F03%20Up%20All%20Night.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Neighborhoods/03 Up All Night.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=blink-182/Neighborhoods/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FNeighborhoods%2F04%20After%20Midnight.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Neighborhoods/04 After Midnight.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=blink-182/Neighborhoods/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FNeighborhoods%2F05%20Heart's%20All%20Gone.mp3&metadata=false Jan 21 16:40:22 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Neighborhoods/05 Heart's All Gone.mp3 Jan 21 16:40:22 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Neighborhoods/06 Wishing Well.mp3 in service mpd Jan 21 16:40:23 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Neighborhoods/07 Kaleidoscope.mp3 in service mpd Jan 21 16:40:23 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Neighborhoods/08 This Is Home.mp3 in service mpd Jan 21 16:40:23 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Neighborhoods/09 MH 4.18.2011.mp3 in service mpd Jan 21 16:40:23 volumio volumio[1295]: info: Exploding uri music-library/NAS/Music/Blink-182/Neighborhoods/10 Love Is Dangerous.mp3 in service mpd Jan 21 16:40:23 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=blink-182/Neighborhoods/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FNeighborhoods%2F06%20Wishing%20Well.mp3&metadata=false Jan 21 16:40:23 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Neighborhoods/06 Wishing Well.mp3 Jan 21 16:40:23 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=blink-182/Neighborhoods/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FNeighborhoods%2F07%20Kaleidoscope.mp3&metadata=false Jan 21 16:40:23 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Neighborhoods/07 Kaleidoscope.mp3 Jan 21 16:40:23 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=blink-182/Neighborhoods/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FNeighborhoods%2F08%20This%20Is%20Home.mp3&metadata=false Jan 21 16:40:23 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Neighborhoods/08 This Is Home.mp3 Jan 21 16:40:23 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=blink-182/Neighborhoods/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FNeighborhoods%2F09%20MH%204.18.2011.mp3&metadata=false Jan 21 16:40:23 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Neighborhoods/09 MH 4.18.2011.mp3 Jan 21 16:40:23 volumio volumio[1295]: info: ALBUMART /albumart?cacheid=934&web=blink-182/Neighborhoods/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FBlink-182%2FNeighborhoods%2F10%20Love%20Is%20Dangerous.mp3&metadata=false Jan 21 16:40:23 volumio volumio[1295]: info: URI /mnt/NAS/Music/Blink-182/Neighborhoods/10 Love Is Dangerous.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:40:26 volumio volumio[1295]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::ClearQueue Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::stop Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::stPlaybackTimer Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::updateTrackBlock Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::getTrackBlock Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:40:26 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:40:26 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::serviceStop Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::getTrack 0 Jan 21 16:40:26 volumio volumio[1295]: info: CoreCommandRouter::serviceStop Jan 21 16:40:26 volumio volumio[1295]: info: ControllerMpd::stop Jan 21 16:40:26 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand stop Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::clearPlayQueue Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::saveQueue Jan 21 16:40:26 volumio volumio[1295]: info: CoreCommandRouter::volumioPushQueue Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::addQueueItems Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::addQueueItems Jan 21 16:40:26 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/01 - Dumpweed.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/01 - Dumpweed.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/02 - Don't Leave Me.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/02 - Don't Leave Me.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/03 - Aliens Exist.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/03 - Aliens Exist.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/04 - Going Away To College.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/04 - Going Away To College.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/05 - What's My Age Again-.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/05 - What's My Age Again-.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/06 - Dysentery Gary.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/06 - Dysentery Gary.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/07 - Adam's Song.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/07 - Adam's Song.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/08 - All The Small Things.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/08 - All The Small Things.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/09 - The Party Song.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/09 - The Party Song.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/10 - Mutt.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/10 - Mutt.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/11 - Wendy Clear.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/11 - Wendy Clear.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/12 - Anthem.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/12 - Anthem.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/01 Ghost on the Dance Floor.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/01 Ghost on the Dance Floor.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/02 Natives.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/02 Natives.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/03 Up All Night.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/03 Up All Night.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/04 After Midnight.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/04 After Midnight.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/05 Heart's All Gone.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/05 Heart's All Gone.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/06 Wishing Well.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/06 Wishing Well.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/07 Kaleidoscope.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/07 Kaleidoscope.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/08 This Is Home.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/08 This Is Home.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/09 MH 4.18.2011.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/09 MH 4.18.2011.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/10 Love Is Dangerous.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/10 Love Is Dangerous.mp3 Jan 21 16:40:26 volumio volumio[1295]: info: CoreCommandRouter::volumioPushQueue Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::saveQueue Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::updateTrackBlock Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::getTrackBlock Jan 21 16:40:26 volumio volumio[1295]: info: CoreCommandRouter::volumioPlay Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::play index 21 Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::stop Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::play index undefined Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::startPlaybackTimer Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:26 volumio volumio[1295]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Blink-182/Neighborhoods/10 Love Is Dangerous.mp3 Jan 21 16:40:26 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand stop Jan 21 16:40:26 volumio volumio[1295]: info: Jan 21 16:40:26 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:26 volumio volumio[1295]: info: sendMpdCommand stop took 11 milliseconds Jan 21 16:40:26 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:26 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:26 volumio volumio[1295]: info: sendMpdCommand stop took 1 milliseconds Jan 21 16:40:26 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand clear Jan 21 16:40:26 volumio volumio[1295]: info: Jan 21 16:40:26 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:40:26 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:40:26 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:40:26 volumio volumio[1295]: info: sendMpdCommand clear took 0 milliseconds Jan 21 16:40:26 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:26 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:40:26 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Blink-182/Neighborhoods/10 Love Is Dangerous.mp3" Jan 21 16:40:26 volumio volumio[1295]: info: Jan 21 16:40:26 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:26 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:26 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:26 volumio volumio[1295]: info: Jan 21 16:40:26 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:26 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:26 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:26 volumio volumio[1295]: error: updateQueue error: null Jan 21 16:40:26 volumio volumio[1295]: info: Jan 21 16:40:26 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:40:26 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:40:26 volumio volumio[1295]: info: ------------------------------ 1ms Jan 21 16:40:26 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 21 16:40:26 volumio volumio[1295]: info: sendMpdCommand add "NAS/Music/Blink-182/Neighborhoods/10 Love Is Dangerous.mp3" took 2 milliseconds Jan 21 16:40:26 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:40:26 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:40:26 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand play Jan 21 16:40:26 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:26 volumio volumio[1295]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 21 16:40:26 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:40:26 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:26 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:40:26 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:26 volumio volumio[1295]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 21 16:40:26 volumio volumio[1295]: verbose: CURRENT POSITION 21 Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::syncState stateService stop Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus stop Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:26 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:26 volumio volumio[1295]: info: No code Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:26 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:26 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:40:26 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:26 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:26 volumio volumio[1295]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 21 16:40:26 volumio volumio[1295]: verbose: CURRENT POSITION 21 Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::syncState stateService stop Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus stop Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:26 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:26 volumio volumio[1295]: info: No code Jan 21 16:40:26 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:26 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:26 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:26 volumio volumio[1295]: info: ------------------------------ 8ms Jan 21 16:40:26 volumio volumio[1295]: info: ------------------------------ 14ms Jan 21 16:40:26 volumio volumio[1295]: info: ------------------------------ 13ms Jan 21 16:40:26 volumio volumio[1295]: info: Jan 21 16:40:26 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:40:26 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:40:26 volumio volumio[1295]: info: Jan 21 16:40:26 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:40:26 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:40:26 volumio volumio[1295]: info: ------------------------------ 14ms Jan 21 16:40:26 volumio volumio[1295]: info: sendMpdCommand play took 13 milliseconds Jan 21 16:40:26 volumio volumio[1295]: info: ------------------------------ 1ms Jan 21 16:40:26 volumio volumio[1295]: info: ------------------------------ 1ms Jan 21 16:40:27 volumio volumio[1295]: info: Jan 21 16:40:27 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:27 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:27 volumio volumio[1295]: info: Jan 21 16:40:27 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:27 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:27 volumio volumio[1295]: info: Jan 21 16:40:27 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:27 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:27 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jan 21 16:40:27 volumio volumio[1295]: info: sendMpdCommand status took 13 milliseconds Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:40:27 volumio volumio[1295]: info: Jan 21 16:40:27 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:27 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:27 volumio volumio[1295]: info: sendMpdCommand status took 14 milliseconds Jan 21 16:40:27 volumio volumio[1295]: info: sendMpdCommand status took 14 milliseconds Jan 21 16:40:27 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:40:27 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:40:27 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:27 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Love Is Dangerous","artist":"blink-182","album":"Neighborhoods","uri":"NAS/Music/Blink-182/Neighborhoods/10 Love Is Dangerous.mp3","trackType":"mp3"} Jan 21 16:40:27 volumio volumio[1295]: verbose: CURRENT POSITION 21 Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus stop Jan 21 16:40:27 volumio volumio[1295]: info: ------------------------------ 16ms Jan 21 16:40:27 volumio volumio[1295]: info: Jan 21 16:40:27 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:27 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:27 volumio volumio[1295]: info: Jan 21 16:40:27 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:27 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:27 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:40:27 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 21 16:40:27 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 21 16:40:27 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:40:27 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:40:27 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:40:27 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:27 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":267,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Love Is Dangerous","artist":"blink-182","album":"Neighborhoods","uri":"NAS/Music/Blink-182/Neighborhoods/10 Love Is Dangerous.mp3","trackType":"mp3"} Jan 21 16:40:27 volumio volumio[1295]: verbose: CURRENT POSITION 21 Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:40:27 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:27 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:27 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:27 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:40:27 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:27 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":267,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Love Is Dangerous","artist":"blink-182","album":"Neighborhoods","uri":"NAS/Music/Blink-182/Neighborhoods/10 Love Is Dangerous.mp3","trackType":"mp3"} Jan 21 16:40:27 volumio volumio[1295]: verbose: CURRENT POSITION 21 Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:40:27 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:27 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:27 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:27 volumio volumio[1295]: info: ------------------------------ 24ms Jan 21 16:40:27 volumio volumio[1295]: info: ------------------------------ 25ms Jan 21 16:40:27 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 21 16:40:27 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 21 16:40:27 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:40:27 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:40:27 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:40:27 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:27 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":267,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Love Is Dangerous","artist":"blink-182","album":"Neighborhoods","uri":"NAS/Music/Blink-182/Neighborhoods/10 Love Is Dangerous.mp3","trackType":"mp3"} Jan 21 16:40:27 volumio volumio[1295]: verbose: CURRENT POSITION 21 Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:40:27 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:27 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:27 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:27 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:40:27 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:27 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":267,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Love Is Dangerous","artist":"blink-182","album":"Neighborhoods","uri":"NAS/Music/Blink-182/Neighborhoods/10 Love Is Dangerous.mp3","trackType":"mp3"} Jan 21 16:40:27 volumio volumio[1295]: verbose: CURRENT POSITION 21 Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:40:27 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:27 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:27 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:27 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:40:27 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:27 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":267,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Love Is Dangerous","artist":"blink-182","album":"Neighborhoods","uri":"NAS/Music/Blink-182/Neighborhoods/10 Love Is Dangerous.mp3","trackType":"mp3"} Jan 21 16:40:27 volumio volumio[1295]: verbose: CURRENT POSITION 21 Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:40:27 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:27 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:27 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:27 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:27 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:27 volumio volumio[1295]: info: ------------------------------ 27ms Jan 21 16:40:27 volumio volumio[1295]: info: ------------------------------ 26ms Jan 21 16:40:27 volumio volumio[1295]: info: ------------------------------ 26ms Jan 21 16:40:30 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::ClearQueue Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::stop Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::stPlaybackTimer Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::updateTrackBlock Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrackBlock Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::serviceStop Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 21 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::serviceStop Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::stop Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand stop Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::clearPlayQueue Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::saveQueue Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushQueue Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::addQueueItems Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::addQueueItems Jan 21 16:40:30 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/01 - Dumpweed.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/01 - Dumpweed.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/02 - Don't Leave Me.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/02 - Don't Leave Me.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/03 - Aliens Exist.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/03 - Aliens Exist.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/04 - Going Away To College.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/04 - Going Away To College.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/05 - What's My Age Again-.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/05 - What's My Age Again-.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/06 - Dysentery Gary.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/06 - Dysentery Gary.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/07 - Adam's Song.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/07 - Adam's Song.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/08 - All The Small Things.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/08 - All The Small Things.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/09 - The Party Song.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/09 - The Party Song.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/10 - Mutt.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/10 - Mutt.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/11 - Wendy Clear.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/11 - Wendy Clear.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Enema of the State/12 - Anthem.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Enema of the State/12 - Anthem.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/01 Ghost on the Dance Floor.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/01 Ghost on the Dance Floor.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/02 Natives.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/02 Natives.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/03 Up All Night.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/03 Up All Night.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/04 After Midnight.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/04 After Midnight.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/05 Heart's All Gone.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/05 Heart's All Gone.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/06 Wishing Well.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/06 Wishing Well.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushQueue Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::saveQueue Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::updateTrackBlock Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrackBlock Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPlay Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::play index 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::addQueueItems Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::addQueueItems Jan 21 16:40:30 volumio volumio[1295]: info: Preload queue cleared Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/07 Kaleidoscope.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/07 Kaleidoscope.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/08 This Is Home.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/08 This Is Home.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/09 MH 4.18.2011.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/09 MH 4.18.2011.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Adding Item to queue: music-library/NAS/Music/Blink-182/Neighborhoods/10 Love Is Dangerous.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: Using cached record of: music-library/NAS/Music/Blink-182/Neighborhoods/10 Love Is Dangerous.mp3 Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::stop Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushQueue Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::saveQueue Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::play index undefined Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::updateTrackBlock Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrackBlock Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::startPlaybackTimer Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Blink-182/Neighborhoods/06 Wishing Well.mp3 Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand stop Jan 21 16:40:30 volumio volumio[1295]: info: Jan 21 16:40:30 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand stop took 13 milliseconds Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand stop took 1 milliseconds Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand clear Jan 21 16:40:30 volumio volumio[1295]: info: Jan 21 16:40:30 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:30 volumio volumio[1295]: info: Jan 21 16:40:30 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:30 volumio volumio[1295]: info: Jan 21 16:40:30 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:40:30 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand status took 2 milliseconds Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand clear took 1 milliseconds Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand status took 1 milliseconds Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Blink-182/Neighborhoods/06 Wishing Well.mp3" Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 21 16:40:30 volumio volumio[1295]: verbose: CURRENT POSITION 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::syncState stateService stop Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus stop Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: No code Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 21 16:40:30 volumio volumio[1295]: verbose: CURRENT POSITION 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::syncState stateService stop Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus stop Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: No code Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: ------------------------------ 15ms Jan 21 16:40:30 volumio volumio[1295]: info: ------------------------------ 16ms Jan 21 16:40:30 volumio volumio[1295]: info: Jan 21 16:40:30 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:40:30 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:40:30 volumio volumio[1295]: info: Jan 21 16:40:30 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:40:30 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:40:30 volumio volumio[1295]: error: updateQueue error: null Jan 21 16:40:30 volumio volumio[1295]: info: Jan 21 16:40:30 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:40:30 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:40:30 volumio volumio[1295]: info: ------------------------------ 16ms Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand add "NAS/Music/Blink-182/Neighborhoods/06 Wishing Well.mp3" took 16 milliseconds Jan 21 16:40:30 volumio volumio[1295]: info: ------------------------------ 1ms Jan 21 16:40:30 volumio volumio[1295]: info: ------------------------------ 1ms Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand play Jan 21 16:40:30 volumio volumio[1295]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 21 16:40:30 volumio volumio[1295]: info: ------------------------------ 18ms Jan 21 16:40:30 volumio volumio[1295]: info: Jan 21 16:40:30 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:40:30 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:40:30 volumio volumio[1295]: info: Jan 21 16:40:30 volumio volumio[1295]: ---------------------------- MPD announces system playlist update Jan 21 16:40:30 volumio volumio[1295]: info: Ignoring MPD Status Update Jan 21 16:40:30 volumio volumio[1295]: info: ------------------------------ 2ms Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand play took 2 milliseconds Jan 21 16:40:30 volumio volumio[1295]: info: ------------------------------ 2ms Jan 21 16:40:30 volumio volumio[1295]: info: Jan 21 16:40:30 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:30 volumio volumio[1295]: info: Jan 21 16:40:30 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:30 volumio volumio[1295]: info: Jan 21 16:40:30 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:30 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jan 21 16:40:30 volumio volumio[1295]: info: Jan 21 16:40:30 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand status took 14 milliseconds Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand status took 14 milliseconds Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand status took 13 milliseconds Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand status took 0 milliseconds Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wishing Well","artist":"blink-182","album":"Neighborhoods","uri":"NAS/Music/Blink-182/Neighborhoods/06 Wishing Well.mp3","trackType":"mp3"} Jan 21 16:40:30 volumio volumio[1295]: verbose: CURRENT POSITION 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus stop Jan 21 16:40:30 volumio volumio[1295]: info: ------------------------------ 15ms Jan 21 16:40:30 volumio volumio[1295]: info: Jan 21 16:40:30 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:30 volumio volumio[1295]: info: Jan 21 16:40:30 volumio volumio[1295]: ---------------------------- MPD announces state update: player Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::getState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand status Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand status took 0 milliseconds Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":200,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wishing Well","artist":"blink-182","album":"Neighborhoods","uri":"NAS/Music/Blink-182/Neighborhoods/06 Wishing Well.mp3","trackType":"mp3"} Jan 21 16:40:30 volumio volumio[1295]: verbose: CURRENT POSITION 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:40:30 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":200,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wishing Well","artist":"blink-182","album":"Neighborhoods","uri":"NAS/Music/Blink-182/Neighborhoods/06 Wishing Well.mp3","trackType":"mp3"} Jan 21 16:40:30 volumio volumio[1295]: verbose: CURRENT POSITION 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:40:30 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":200,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wishing Well","artist":"blink-182","album":"Neighborhoods","uri":"NAS/Music/Blink-182/Neighborhoods/06 Wishing Well.mp3","trackType":"mp3"} Jan 21 16:40:30 volumio volumio[1295]: verbose: CURRENT POSITION 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:40:30 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: ------------------------------ 31ms Jan 21 16:40:30 volumio volumio[1295]: info: ------------------------------ 30ms Jan 21 16:40:30 volumio volumio[1295]: info: ------------------------------ 18ms Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand status took 16 milliseconds Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::parseState Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":200,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wishing Well","artist":"blink-182","album":"Neighborhoods","uri":"NAS/Music/Blink-182/Neighborhoods/06 Wishing Well.mp3","trackType":"mp3"} Jan 21 16:40:30 volumio volumio[1295]: verbose: CURRENT POSITION 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:40:30 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: ------------------------------ 20ms Jan 21 16:40:30 volumio volumio[1295]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 21 16:40:30 volumio volumio[1295]: verbose: ControllerMpd::parseTrackInfo Jan 21 16:40:30 volumio volumio[1295]: info: ControllerMpd::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::servicePushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":200,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wishing Well","artist":"blink-182","album":"Neighborhoods","uri":"NAS/Music/Blink-182/Neighborhoods/06 Wishing Well.mp3","trackType":"mp3"} Jan 21 16:40:30 volumio volumio[1295]: verbose: CURRENT POSITION 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::syncState stateService play Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::syncState currentStatus play Jan 21 16:40:30 volumio volumio[1295]: info: Received an update from plugin. extracting info from payload Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: CoreStateMachine::pushState Jan 21 16:40:30 volumio volumio[1295]: info: CorePlayQueue::getTrack 17 Jan 21 16:40:30 volumio volumio[1295]: info: CoreCommandRouter::volumioPushState Jan 21 16:40:30 volumio volumio[1295]: info: ------------------------------ 27ms Jan 21 16:40:49 volumio volumio[1295]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 16:40:49 volumio volumio[1295]: Error: connect ETIMEDOUT 151.101.190.79:80 Jan 21 16:40:49 volumio volumio[1295]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jan 21 16:40:49 volumio volumio[1295]: errno: -110, Jan 21 16:40:49 volumio volumio[1295]: code: 'ETIMEDOUT', Jan 21 16:40:49 volumio volumio[1295]: syscall: 'connect', Jan 21 16:40:49 volumio volumio[1295]: address: '151.101.190.79', Jan 21 16:40:49 volumio volumio[1295]: port: 80 Jan 21 16:40:49 volumio volumio[1295]: } Jan 21 16:40:49 volumio volumio[1295]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 16:40:49 volumio sudo[2391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-21 16:39 Jan 21 16:40:49 volumio sudo[2391]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"