-- Logs begin at Fri 2024-11-01 06:35:23 UTC, end at Fri 2024-11-01 06:43:22 UTC. -- Nov 01 06:42:20 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 01 06:42:20 volumio volumio[897]: info: CURURI: music-library/INTERNAL/Aebersold_Jobim Nov 01 06:42:20 volumio volumio[897]: info: Preload queue cleared Nov 01 06:42:20 volumio volumio[897]: info: Preloading song: music-library/INTERNAL/Aebersold_Jobim/01.Tuning Notes.flac Nov 01 06:42:20 volumio volumio[897]: info: Preloading song: music-library/INTERNAL/Aebersold_Jobim/02.Corcovado.flac Nov 01 06:42:20 volumio volumio[897]: info: Preloading song: music-library/INTERNAL/Aebersold_Jobim/03.Wave.flac Nov 01 06:42:20 volumio volumio[897]: info: Preloading song: music-library/INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac Nov 01 06:42:20 volumio volumio[897]: info: Preloading song: music-library/INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac Nov 01 06:42:20 volumio volumio[897]: info: Preloading song: music-library/INTERNAL/Aebersold_Jobim/06.Triste.flac Nov 01 06:42:20 volumio volumio[897]: info: Preloading song: music-library/INTERNAL/Aebersold_Jobim/07.One Note Samba.flac Nov 01 06:42:20 volumio volumio[897]: info: Preloading song: music-library/INTERNAL/Aebersold_Jobim/08.How Insensitive.flac Nov 01 06:42:20 volumio volumio[897]: info: Preloading song: music-library/INTERNAL/Aebersold_Jobim/09.The Girl From Ipanema.flac Nov 01 06:42:20 volumio volumio[897]: info: Preloading song: music-library/INTERNAL/Aebersold_Jobim/10.Meditation.flac Nov 01 06:42:20 volumio volumio[897]: info: Preloading song: music-library/INTERNAL/Aebersold_Jobim/11.Once I Loved.flac Nov 01 06:42:20 volumio volumio[897]: info: Preloading song: music-library/INTERNAL/Aebersold_Jobim/12.Desafinado.flac Nov 01 06:42:20 volumio volumio[897]: info: Preloading song: music-library/INTERNAL/Aebersold_Jobim/13.No More Blues.flac Nov 01 06:42:20 volumio volumio[897]: info: Exploding uri music-library/INTERNAL/Aebersold_Jobim/01.Tuning Notes.flac in service mpd Nov 01 06:42:20 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F01.Tuning%20Notes.flac&metadata=false Nov 01 06:42:20 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/01.Tuning Notes.flac Nov 01 06:42:20 volumio volumio[897]: info: Exploding uri music-library/INTERNAL/Aebersold_Jobim/02.Corcovado.flac in service mpd Nov 01 06:42:20 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F02.Corcovado.flac&metadata=false Nov 01 06:42:20 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/02.Corcovado.flac Nov 01 06:42:20 volumio volumio[897]: info: Exploding uri music-library/INTERNAL/Aebersold_Jobim/03.Wave.flac in service mpd Nov 01 06:42:20 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F03.Wave.flac&metadata=false Nov 01 06:42:20 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/03.Wave.flac Nov 01 06:42:20 volumio volumio[897]: info: Exploding uri music-library/INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac in service mpd Nov 01 06:42:20 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F04.So%20Danco%20Samba.flac&metadata=false Nov 01 06:42:20 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac Nov 01 06:42:20 volumio volumio[897]: info: Exploding uri music-library/INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac in service mpd Nov 01 06:42:20 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F05.O%20Grande%20Amor.flac&metadata=false Nov 01 06:42:20 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac Nov 01 06:42:20 volumio volumio[897]: info: Exploding uri music-library/INTERNAL/Aebersold_Jobim/06.Triste.flac in service mpd Nov 01 06:42:20 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F06.Triste.flac&metadata=false Nov 01 06:42:20 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/06.Triste.flac Nov 01 06:42:20 volumio volumio[897]: info: Exploding uri music-library/INTERNAL/Aebersold_Jobim/07.One Note Samba.flac in service mpd Nov 01 06:42:20 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F07.One%20Note%20Samba.flac&metadata=false Nov 01 06:42:20 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/07.One Note Samba.flac Nov 01 06:42:20 volumio volumio[897]: info: Exploding uri music-library/INTERNAL/Aebersold_Jobim/08.How Insensitive.flac in service mpd Nov 01 06:42:20 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F08.How%20Insensitive.flac&metadata=false Nov 01 06:42:20 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/08.How Insensitive.flac Nov 01 06:42:20 volumio volumio[897]: info: Exploding uri music-library/INTERNAL/Aebersold_Jobim/09.The Girl From Ipanema.flac in service mpd Nov 01 06:42:20 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F09.The%20Girl%20From%20Ipanema.flac&metadata=false Nov 01 06:42:20 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/09.The Girl From Ipanema.flac Nov 01 06:42:20 volumio volumio[897]: info: Exploding uri music-library/INTERNAL/Aebersold_Jobim/10.Meditation.flac in service mpd Nov 01 06:42:20 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F10.Meditation.flac&metadata=false Nov 01 06:42:20 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/10.Meditation.flac Nov 01 06:42:20 volumio volumio[897]: info: Exploding uri music-library/INTERNAL/Aebersold_Jobim/11.Once I Loved.flac in service mpd Nov 01 06:42:20 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F11.Once%20I%20Loved.flac&metadata=false Nov 01 06:42:20 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/11.Once I Loved.flac Nov 01 06:42:20 volumio volumio[897]: info: Exploding uri music-library/INTERNAL/Aebersold_Jobim/12.Desafinado.flac in service mpd Nov 01 06:42:20 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F12.Desafinado.flac&metadata=false Nov 01 06:42:20 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/12.Desafinado.flac Nov 01 06:42:20 volumio volumio[897]: info: Exploding uri music-library/INTERNAL/Aebersold_Jobim/13.No More Blues.flac in service mpd Nov 01 06:42:20 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F13.No%20More%20Blues.flac&metadata=false Nov 01 06:42:20 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/13.No More Blues.flac Nov 01 06:42:24 volumio volumio[897]: info: Preload queue cleared Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::ClearQueue Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::stop Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::stPlaybackTimer Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::serviceStop Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::serviceStop Nov 01 06:42:24 volumio volumio[897]: info: ControllerMpd::stop Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::clearPlayQueue Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::saveQueue Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushQueue Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::addQueueItems Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::addQueueItems Nov 01 06:42:24 volumio volumio[897]: info: Preload queue cleared Nov 01 06:42:24 volumio volumio[897]: info: Adding Item to queue: music-library/INTERNAL/Aebersold_Jobim Nov 01 06:42:24 volumio volumio[897]: info: Exploding uri music-library/INTERNAL/Aebersold_Jobim in service mpd Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:24 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F01.Tuning%20Notes.flac&metadata=false Nov 01 06:42:24 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/01.Tuning Notes.flac Nov 01 06:42:24 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F02.Corcovado.flac&metadata=false Nov 01 06:42:24 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/02.Corcovado.flac Nov 01 06:42:24 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F03.Wave.flac&metadata=false Nov 01 06:42:24 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/03.Wave.flac Nov 01 06:42:24 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F04.So%20Danco%20Samba.flac&metadata=false Nov 01 06:42:24 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac Nov 01 06:42:24 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F05.O%20Grande%20Amor.flac&metadata=false Nov 01 06:42:24 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac Nov 01 06:42:24 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F06.Triste.flac&metadata=false Nov 01 06:42:24 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/06.Triste.flac Nov 01 06:42:24 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F07.One%20Note%20Samba.flac&metadata=false Nov 01 06:42:24 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/07.One Note Samba.flac Nov 01 06:42:24 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F08.How%20Insensitive.flac&metadata=false Nov 01 06:42:24 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/08.How Insensitive.flac Nov 01 06:42:24 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F09.The%20Girl%20From%20Ipanema.flac&metadata=false Nov 01 06:42:24 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/09.The Girl From Ipanema.flac Nov 01 06:42:24 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F10.Meditation.flac&metadata=false Nov 01 06:42:24 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/10.Meditation.flac Nov 01 06:42:24 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F11.Once%20I%20Loved.flac&metadata=false Nov 01 06:42:24 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/11.Once I Loved.flac Nov 01 06:42:24 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F12.Desafinado.flac&metadata=false Nov 01 06:42:24 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/12.Desafinado.flac Nov 01 06:42:24 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FAebersold_Jobim%2F13.No%20More%20Blues.flac&metadata=false Nov 01 06:42:24 volumio volumio[897]: info: URI /mnt/INTERNAL/Aebersold_Jobim/13.No More Blues.flac Nov 01 06:42:24 volumio volumio[897]: info: Nov 01 06:42:24 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:42:24 volumio volumio[897]: info: sendMpdCommand stop took 7 milliseconds Nov 01 06:42:24 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushQueue Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::saveQueue Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::volumioPlay Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::play index 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::stop Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::play index undefined Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Aebersold_Jobim/01.Tuning Notes.flac Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 06:42:24 volumio volumio[897]: info: Nov 01 06:42:24 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:42:24 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:42:24 volumio volumio[897]: info: sendMpdCommand status took 5 milliseconds Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:42:24 volumio volumio[897]: info: sendMpdCommand stop took 2 milliseconds Nov 01 06:42:24 volumio volumio[897]: info: sendMpdCommand status took 2 milliseconds Nov 01 06:42:24 volumio volumio[897]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand clear Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:42:24 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lost in Love","artist":"Air Supply","album":"The Ultimate Collection","uri":"INTERNAL/Air Supply - The Ultimate Collection [Disc 1]/01. Lost in Love.flac","trackType":"flac"} Nov 01 06:42:24 volumio volumio[897]: verbose: CURRENT POSITION 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::syncState stateService stop Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:24 volumio volumio[897]: info: No code Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:24 volumio volumio[897]: info: ------------------------------ 11ms Nov 01 06:42:24 volumio volumio[897]: info: Nov 01 06:42:24 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:42:24 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:42:24 volumio volumio[897]: info: sendMpdCommand clear took 5 milliseconds Nov 01 06:42:24 volumio volumio[897]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Aebersold_Jobim/01.Tuning Notes.flac" Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:42:24 volumio volumio[897]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 01 06:42:24 volumio volumio[897]: info: ------------------------------ 8ms Nov 01 06:42:24 volumio volumio[897]: info: Nov 01 06:42:24 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:42:24 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:42:24 volumio volumio[897]: error: updateQueue error: null Nov 01 06:42:24 volumio volumio[897]: info: Nov 01 06:42:24 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:42:24 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:42:24 volumio volumio[897]: info: ------------------------------ 18ms Nov 01 06:42:24 volumio volumio[897]: info: sendMpdCommand add "INTERNAL/Aebersold_Jobim/01.Tuning Notes.flac" took 17 milliseconds Nov 01 06:42:24 volumio volumio[897]: info: ------------------------------ 13ms Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand play Nov 01 06:42:24 volumio volumio[897]: info: Nov 01 06:42:24 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:42:24 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:42:24 volumio volumio[897]: info: ------------------------------ 3ms Nov 01 06:42:24 volumio volumio[897]: info: sendMpdCommand play took 3 milliseconds Nov 01 06:42:24 volumio volumio[897]: info: ------------------------------ 2ms Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:24 volumio volumio[897]: info: Nov 01 06:42:24 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:42:24 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:42:24 volumio volumio[897]: info: Nov 01 06:42:24 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:42:24 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:42:24 volumio volumio[897]: info: Nov 01 06:42:24 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:42:24 volumio volumio[897]: info: sendMpdCommand status took 5 milliseconds Nov 01 06:42:24 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:42:24 volumio volumio[897]: info: sendMpdCommand status took 4 milliseconds Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:42:24 volumio volumio[897]: info: Nov 01 06:42:24 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:42:24 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:42:24 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:42:24 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 01 06:42:24 volumio volumio[897]: info: sendMpdCommand status took 0 milliseconds Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:42:24 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":34,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"587 Kbps","isStreaming":false,"title":"01.Tuning Notes.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/01.Tuning Notes.flac","trackType":"flac"} Nov 01 06:42:24 volumio volumio[897]: verbose: CURRENT POSITION 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Nov 01 06:42:24 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":34,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"587 Kbps","isStreaming":false,"title":"01.Tuning Notes.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/01.Tuning Notes.flac","trackType":"flac"} Nov 01 06:42:24 volumio volumio[897]: verbose: CURRENT POSITION 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:42:24 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:24 volumio volumio[897]: info: ------------------------------ 10ms Nov 01 06:42:24 volumio volumio[897]: info: ------------------------------ 15ms Nov 01 06:42:24 volumio volumio[897]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 01 06:42:24 volumio volumio[897]: info: sendMpdCommand playlistinfo took 8 milliseconds Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:42:24 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:42:24 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":34,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"357 Kbps","isStreaming":false,"title":"01.Tuning Notes.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/01.Tuning Notes.flac","trackType":"flac"} Nov 01 06:42:24 volumio volumio[897]: verbose: CURRENT POSITION 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:42:24 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:24 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":34,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"357 Kbps","isStreaming":false,"title":"01.Tuning Notes.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/01.Tuning Notes.flac","trackType":"flac"} Nov 01 06:42:24 volumio volumio[897]: verbose: CURRENT POSITION 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:42:24 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:24 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:24 volumio volumio[897]: info: ------------------------------ 27ms Nov 01 06:42:24 volumio volumio[897]: info: ------------------------------ 26ms Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:24 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::volumioNext Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::next Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::stop Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::stPlaybackTimer Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::serviceStop Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::serviceStop Nov 01 06:42:47 volumio volumio[897]: info: ControllerMpd::stop Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:47 volumio volumio[897]: info: Nov 01 06:42:47 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand stop took 3 milliseconds Nov 01 06:42:47 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::play index undefined Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Aebersold_Jobim/02.Corcovado.flac Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Nov 01 06:42:47 volumio volumio[897]: info: Nov 01 06:42:47 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:42:47 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand stop took 1 milliseconds Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand clear Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:42:47 volumio volumio[897]: info: Nov 01 06:42:47 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:42:47 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand clear took 1 milliseconds Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Aebersold_Jobim/02.Corcovado.flac" Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:42:47 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:42:47 volumio volumio[897]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.Tuning Notes.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/01.Tuning Notes.flac","trackType":"flac"} Nov 01 06:42:47 volumio volumio[897]: verbose: CURRENT POSITION 1 Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::syncState stateService stop Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:47 volumio volumio[897]: info: No code Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:47 volumio volumio[897]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 01 06:42:47 volumio volumio[897]: info: ------------------------------ 13ms Nov 01 06:42:47 volumio volumio[897]: info: ------------------------------ 12ms Nov 01 06:42:47 volumio volumio[897]: info: Nov 01 06:42:47 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:42:47 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:42:47 volumio volumio[897]: error: updateQueue error: null Nov 01 06:42:47 volumio volumio[897]: info: Nov 01 06:42:47 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:42:47 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:42:47 volumio volumio[897]: info: ------------------------------ 11ms Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand add "INTERNAL/Aebersold_Jobim/02.Corcovado.flac" took 11 milliseconds Nov 01 06:42:47 volumio volumio[897]: info: ------------------------------ 1ms Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand play Nov 01 06:42:47 volumio volumio[897]: info: Nov 01 06:42:47 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:42:47 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:47 volumio volumio[897]: info: ------------------------------ 6ms Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand play took 6 milliseconds Nov 01 06:42:47 volumio volumio[897]: info: ------------------------------ 5ms Nov 01 06:42:47 volumio volumio[897]: info: Nov 01 06:42:47 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:42:47 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:42:47 volumio volumio[897]: info: Nov 01 06:42:47 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:42:47 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:42:47 volumio volumio[897]: info: Nov 01 06:42:47 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:42:47 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:42:47 volumio volumio[897]: info: Nov 01 06:42:47 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:42:47 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand status took 2 milliseconds Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:42:47 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:42:47 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"315 Kbps","isStreaming":false,"title":"02.Corcovado.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/02.Corcovado.flac","trackType":"flac"} Nov 01 06:42:47 volumio volumio[897]: verbose: CURRENT POSITION 1 Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Nov 01 06:42:47 volumio volumio[897]: info: ------------------------------ 5ms Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand status took 3 milliseconds Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand status took 3 milliseconds Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:42:47 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:42:47 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"316 Kbps","isStreaming":false,"title":"02.Corcovado.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/02.Corcovado.flac","trackType":"flac"} Nov 01 06:42:47 volumio volumio[897]: verbose: CURRENT POSITION 1 Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:42:47 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:47 volumio volumio[897]: info: ------------------------------ 11ms Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 01 06:42:47 volumio volumio[897]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:42:47 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:42:47 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:42:47 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"354 Kbps","isStreaming":false,"title":"02.Corcovado.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/02.Corcovado.flac","trackType":"flac"} Nov 01 06:42:47 volumio volumio[897]: verbose: CURRENT POSITION 1 Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:42:47 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:47 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:42:47 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"354 Kbps","isStreaming":false,"title":"02.Corcovado.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/02.Corcovado.flac","trackType":"flac"} Nov 01 06:42:47 volumio volumio[897]: verbose: CURRENT POSITION 1 Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:42:47 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:47 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:42:47 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:42:47 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:42:47 volumio volumio[897]: info: ------------------------------ 20ms Nov 01 06:42:47 volumio volumio[897]: info: ------------------------------ 21ms Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:42:47 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:00 volumio volumio[897]: info: CoreCommandRouter::volumioSeek Nov 01 06:43:00 volumio volumio[897]: info: CoreStateMachine::seek Nov 01 06:43:00 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:00 volumio volumio[897]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Aebersold_Jobim/02.Corcovado.flac","service":"mpd","name":"02.Corcovado.flac","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=563&web=//extralarge&path=%2FINTERNAL%2FAebersold_Jobim&metadata=false","duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Nov 01 06:43:00 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Nov 01 06:43:00 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:00 volumio volumio[897]: info: ControllerMpd::seek Nov 01 06:43:00 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:00 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:00 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:00 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:00 volumio volumio[897]: error: null Nov 01 06:43:00 volumio volumio[897]: info: Nov 01 06:43:00 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:00 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:00 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:00 volumio volumio[897]: info: Nov 01 06:43:00 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:00 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:00 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:00 volumio volumio[897]: info: sendMpdCommand status took 2 milliseconds Nov 01 06:43:00 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:00 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:00 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:00 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:00 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:00 volumio volumio[897]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 01 06:43:00 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:00 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:00 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:00 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:00 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":75000,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11466 Kbps","isStreaming":false,"title":"02.Corcovado.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/02.Corcovado.flac","trackType":"flac"} Nov 01 06:43:00 volumio volumio[897]: verbose: CURRENT POSITION 1 Nov 01 06:43:00 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:00 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:00 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:00 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:00 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:00 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:00 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:00 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:00 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:00 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:00 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:00 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:00 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":75000,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"727 Kbps","isStreaming":false,"title":"02.Corcovado.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/02.Corcovado.flac","trackType":"flac"} Nov 01 06:43:00 volumio volumio[897]: verbose: CURRENT POSITION 1 Nov 01 06:43:00 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:00 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:00 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:00 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:00 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:00 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:00 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:00 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:00 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:00 volumio volumio[897]: info: ------------------------------ 18ms Nov 01 06:43:00 volumio volumio[897]: info: ------------------------------ 17ms Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:00 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:02 volumio volumio[897]: info: CoreCommandRouter::volumioSeek Nov 01 06:43:02 volumio volumio[897]: info: CoreStateMachine::seek Nov 01 06:43:02 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:02 volumio volumio[897]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Aebersold_Jobim/02.Corcovado.flac","service":"mpd","name":"02.Corcovado.flac","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=563&web=//extralarge&path=%2FINTERNAL%2FAebersold_Jobim&metadata=false","duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Nov 01 06:43:02 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Nov 01 06:43:02 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:02 volumio volumio[897]: info: ControllerMpd::seek Nov 01 06:43:02 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:02 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:02 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:02 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:02 volumio volumio[897]: error: null Nov 01 06:43:02 volumio volumio[897]: info: Nov 01 06:43:02 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:02 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:02 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:02 volumio volumio[897]: info: Nov 01 06:43:02 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:02 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:02 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:02 volumio volumio[897]: info: sendMpdCommand status took 0 milliseconds Nov 01 06:43:02 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:02 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:02 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:02 volumio volumio[897]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 01 06:43:02 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:02 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:02 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:02 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:02 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:02 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:02 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":141000,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"678 Kbps","isStreaming":false,"title":"02.Corcovado.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/02.Corcovado.flac","trackType":"flac"} Nov 01 06:43:02 volumio volumio[897]: verbose: CURRENT POSITION 1 Nov 01 06:43:02 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:02 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:02 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:02 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:02 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:02 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:02 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:02 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:02 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:02 volumio volumio[897]: info: ------------------------------ 7ms Nov 01 06:43:02 volumio volumio[897]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 01 06:43:02 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:02 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:02 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:02 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:02 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":141000,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"678 Kbps","isStreaming":false,"title":"02.Corcovado.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/02.Corcovado.flac","trackType":"flac"} Nov 01 06:43:02 volumio volumio[897]: verbose: CURRENT POSITION 1 Nov 01 06:43:02 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:02 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:02 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:02 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:02 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:02 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:02 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:02 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:02 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:02 volumio volumio[897]: info: ------------------------------ 14ms Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:02 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::volumioNext Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::next Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::stop Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::stPlaybackTimer Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::serviceStop Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 1 Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::serviceStop Nov 01 06:43:03 volumio volumio[897]: info: ControllerMpd::stop Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:03 volumio volumio[897]: info: Nov 01 06:43:03 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand stop took 3 milliseconds Nov 01 06:43:03 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::play index undefined Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Aebersold_Jobim/03.Wave.flac Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Nov 01 06:43:03 volumio volumio[897]: info: Nov 01 06:43:03 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:03 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand stop took 1 milliseconds Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand status took 0 milliseconds Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand clear Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:03 volumio volumio[897]: info: Nov 01 06:43:03 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:43:03 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand clear took 0 milliseconds Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Aebersold_Jobim/03.Wave.flac" Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:03 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:03 volumio volumio[897]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.Corcovado.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/02.Corcovado.flac","trackType":"flac"} Nov 01 06:43:03 volumio volumio[897]: verbose: CURRENT POSITION 2 Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::syncState stateService stop Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:03 volumio volumio[897]: info: No code Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:03 volumio volumio[897]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 01 06:43:03 volumio volumio[897]: info: ------------------------------ 10ms Nov 01 06:43:03 volumio volumio[897]: info: Nov 01 06:43:03 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:43:03 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:43:03 volumio volumio[897]: error: updateQueue error: null Nov 01 06:43:03 volumio volumio[897]: info: Nov 01 06:43:03 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:43:03 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:43:03 volumio volumio[897]: info: ------------------------------ 9ms Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand add "INTERNAL/Aebersold_Jobim/03.Wave.flac" took 8 milliseconds Nov 01 06:43:03 volumio volumio[897]: info: ------------------------------ 0ms Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand play Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:03 volumio volumio[897]: info: ------------------------------ 4ms Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand play took 4 milliseconds Nov 01 06:43:03 volumio volumio[897]: info: Nov 01 06:43:03 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:43:03 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:43:03 volumio volumio[897]: info: ------------------------------ 3ms Nov 01 06:43:03 volumio volumio[897]: info: Nov 01 06:43:03 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:03 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:03 volumio volumio[897]: info: Nov 01 06:43:03 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:03 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:03 volumio volumio[897]: info: Nov 01 06:43:03 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:03 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand status took 0 milliseconds Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand status took 0 milliseconds Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:03 volumio volumio[897]: info: Nov 01 06:43:03 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:03 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand status took 0 milliseconds Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:03 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:03 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":374,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03.Wave.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/03.Wave.flac","trackType":"flac"} Nov 01 06:43:03 volumio volumio[897]: verbose: CURRENT POSITION 2 Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Nov 01 06:43:03 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:03 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":374,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03.Wave.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/03.Wave.flac","trackType":"flac"} Nov 01 06:43:03 volumio volumio[897]: verbose: CURRENT POSITION 2 Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:03 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:03 volumio volumio[897]: info: ------------------------------ 6ms Nov 01 06:43:03 volumio volumio[897]: info: ------------------------------ 9ms Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 01 06:43:03 volumio volumio[897]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:03 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:03 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:03 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":374,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03.Wave.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/03.Wave.flac","trackType":"flac"} Nov 01 06:43:03 volumio volumio[897]: verbose: CURRENT POSITION 2 Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:03 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:03 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:03 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":374,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03.Wave.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/03.Wave.flac","trackType":"flac"} Nov 01 06:43:03 volumio volumio[897]: verbose: CURRENT POSITION 2 Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:03 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:03 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:03 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:03 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:03 volumio volumio[897]: info: ------------------------------ 31ms Nov 01 06:43:03 volumio volumio[897]: info: ------------------------------ 31ms Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:03 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:05 volumio volumio[897]: info: CoreCommandRouter::volumioSeek Nov 01 06:43:05 volumio volumio[897]: info: CoreStateMachine::seek Nov 01 06:43:05 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:05 volumio volumio[897]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Aebersold_Jobim/03.Wave.flac","service":"mpd","name":"03.Wave.flac","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=563&web=//extralarge&path=%2FINTERNAL%2FAebersold_Jobim&metadata=false","duration":374,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Nov 01 06:43:05 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Nov 01 06:43:05 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:05 volumio volumio[897]: info: ControllerMpd::seek Nov 01 06:43:05 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:05 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:05 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:05 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:05 volumio volumio[897]: error: null Nov 01 06:43:05 volumio volumio[897]: info: Nov 01 06:43:05 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:05 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:05 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:05 volumio volumio[897]: info: Nov 01 06:43:05 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:05 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:05 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:05 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:05 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:05 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:05 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:05 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:05 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:05 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 01 06:43:05 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 01 06:43:05 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:05 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:05 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:05 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:05 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:05 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":110000,"duration":374,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"420 Kbps","isStreaming":false,"title":"03.Wave.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/03.Wave.flac","trackType":"flac"} Nov 01 06:43:05 volumio volumio[897]: verbose: CURRENT POSITION 2 Nov 01 06:43:05 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:05 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:05 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:05 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:05 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:05 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:05 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:05 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:05 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:05 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:05 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:05 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:05 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":110000,"duration":374,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"420 Kbps","isStreaming":false,"title":"03.Wave.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/03.Wave.flac","trackType":"flac"} Nov 01 06:43:05 volumio volumio[897]: verbose: CURRENT POSITION 2 Nov 01 06:43:05 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:05 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:05 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:05 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:05 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:05 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:05 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:05 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:05 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:05 volumio volumio[897]: info: ------------------------------ 15ms Nov 01 06:43:05 volumio volumio[897]: info: ------------------------------ 15ms Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:05 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:07 volumio volumio[897]: info: CoreCommandRouter::volumioSeek Nov 01 06:43:07 volumio volumio[897]: info: CoreStateMachine::seek Nov 01 06:43:07 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:07 volumio volumio[897]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Aebersold_Jobim/03.Wave.flac","service":"mpd","name":"03.Wave.flac","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=563&web=//extralarge&path=%2FINTERNAL%2FAebersold_Jobim&metadata=false","duration":374,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Nov 01 06:43:07 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Nov 01 06:43:07 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:07 volumio volumio[897]: info: ControllerMpd::seek Nov 01 06:43:07 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:07 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:07 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:07 volumio volumio[897]: error: null Nov 01 06:43:07 volumio volumio[897]: info: Nov 01 06:43:07 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:07 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:07 volumio volumio[897]: info: Nov 01 06:43:07 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:07 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:07 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:07 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:07 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:07 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:07 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 01 06:43:07 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:07 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:07 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:07 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:07 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":177000,"duration":374,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"9508 Kbps","isStreaming":false,"title":"03.Wave.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/03.Wave.flac","trackType":"flac"} Nov 01 06:43:07 volumio volumio[897]: verbose: CURRENT POSITION 2 Nov 01 06:43:07 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:07 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:07 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:07 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:07 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:07 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:07 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:07 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:07 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:07 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:07 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":177000,"duration":374,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"858 Kbps","isStreaming":false,"title":"03.Wave.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/03.Wave.flac","trackType":"flac"} Nov 01 06:43:07 volumio volumio[897]: verbose: CURRENT POSITION 2 Nov 01 06:43:07 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:07 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:07 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:07 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:07 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:07 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:07 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:07 volumio volumio[897]: info: ------------------------------ 13ms Nov 01 06:43:07 volumio volumio[897]: info: ------------------------------ 13ms Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:07 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:08 volumio volumio[897]: info: CoreCommandRouter::volumioSeek Nov 01 06:43:08 volumio volumio[897]: info: CoreStateMachine::seek Nov 01 06:43:08 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:08 volumio volumio[897]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Aebersold_Jobim/03.Wave.flac","service":"mpd","name":"03.Wave.flac","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=563&web=//extralarge&path=%2FINTERNAL%2FAebersold_Jobim&metadata=false","duration":374,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Nov 01 06:43:08 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Nov 01 06:43:08 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:08 volumio volumio[897]: info: ControllerMpd::seek Nov 01 06:43:08 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:08 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:08 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:08 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:08 volumio volumio[897]: error: null Nov 01 06:43:08 volumio volumio[897]: info: Nov 01 06:43:08 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:08 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:08 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:08 volumio volumio[897]: info: Nov 01 06:43:08 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:08 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:08 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:08 volumio volumio[897]: info: sendMpdCommand status took 3 milliseconds Nov 01 06:43:08 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:08 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:08 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:08 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:08 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:08 volumio volumio[897]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 01 06:43:08 volumio volumio[897]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 01 06:43:08 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:08 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:08 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:08 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:08 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:08 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":277000,"duration":374,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"757 Kbps","isStreaming":false,"title":"03.Wave.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/03.Wave.flac","trackType":"flac"} Nov 01 06:43:08 volumio volumio[897]: verbose: CURRENT POSITION 2 Nov 01 06:43:08 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:08 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:08 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:08 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:08 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:08 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:08 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:08 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:08 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:08 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:08 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:08 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:08 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":277866,"duration":374,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"910 Kbps","isStreaming":false,"title":"03.Wave.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/03.Wave.flac","trackType":"flac"} Nov 01 06:43:08 volumio volumio[897]: verbose: CURRENT POSITION 2 Nov 01 06:43:08 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:08 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:08 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:08 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:08 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:08 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:08 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:08 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:08 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:08 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:08 volumio volumio[897]: info: ------------------------------ 16ms Nov 01 06:43:08 volumio volumio[897]: info: ------------------------------ 14ms Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:08 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::volumioNext Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::next Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::stop Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::stPlaybackTimer Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::serviceStop Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 2 Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::serviceStop Nov 01 06:43:09 volumio volumio[897]: info: ControllerMpd::stop Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:09 volumio volumio[897]: info: Nov 01 06:43:09 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:09 volumio volumio[897]: info: sendMpdCommand stop took 2 milliseconds Nov 01 06:43:09 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::play index undefined Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Nov 01 06:43:09 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:09 volumio volumio[897]: info: sendMpdCommand stop took 0 milliseconds Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand clear Nov 01 06:43:09 volumio volumio[897]: info: Nov 01 06:43:09 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:09 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:09 volumio volumio[897]: info: Nov 01 06:43:09 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:43:09 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:43:09 volumio volumio[897]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 01 06:43:09 volumio volumio[897]: info: sendMpdCommand clear took 2 milliseconds Nov 01 06:43:09 volumio volumio[897]: info: sendMpdCommand status took 2 milliseconds Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac" Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:09 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03.Wave.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/03.Wave.flac","trackType":"flac"} Nov 01 06:43:09 volumio volumio[897]: verbose: CURRENT POSITION 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::syncState stateService stop Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:09 volumio volumio[897]: info: No code Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:09 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 01 06:43:09 volumio volumio[897]: verbose: CURRENT POSITION 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::syncState stateService stop Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:09 volumio volumio[897]: info: No code Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:09 volumio volumio[897]: info: ------------------------------ 15ms Nov 01 06:43:09 volumio volumio[897]: info: ------------------------------ 15ms Nov 01 06:43:09 volumio volumio[897]: info: Nov 01 06:43:09 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:43:09 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:43:09 volumio volumio[897]: error: updateQueue error: null Nov 01 06:43:09 volumio volumio[897]: info: Nov 01 06:43:09 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:43:09 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:43:09 volumio volumio[897]: info: ------------------------------ 15ms Nov 01 06:43:09 volumio volumio[897]: info: sendMpdCommand add "INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac" took 14 milliseconds Nov 01 06:43:09 volumio volumio[897]: info: ------------------------------ 0ms Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand play Nov 01 06:43:09 volumio volumio[897]: info: Nov 01 06:43:09 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:43:09 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:09 volumio volumio[897]: info: Nov 01 06:43:09 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:09 volumio volumio[897]: info: ------------------------------ 9ms Nov 01 06:43:09 volumio volumio[897]: info: sendMpdCommand play took 9 milliseconds Nov 01 06:43:09 volumio volumio[897]: info: ------------------------------ 8ms Nov 01 06:43:09 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:09 volumio volumio[897]: info: Nov 01 06:43:09 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:09 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:09 volumio volumio[897]: info: Nov 01 06:43:09 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:09 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:09 volumio volumio[897]: info: Nov 01 06:43:09 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:09 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:09 volumio volumio[897]: info: sendMpdCommand status took 2 milliseconds Nov 01 06:43:09 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:09 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:09 volumio volumio[897]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 01 06:43:09 volumio volumio[897]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:09 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"361 Kbps","isStreaming":false,"title":"04.So Danco Samba.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac","trackType":"flac"} Nov 01 06:43:09 volumio volumio[897]: verbose: CURRENT POSITION 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Nov 01 06:43:09 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"361 Kbps","isStreaming":false,"title":"04.So Danco Samba.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac","trackType":"flac"} Nov 01 06:43:09 volumio volumio[897]: verbose: CURRENT POSITION 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:09 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:09 volumio volumio[897]: info: ------------------------------ 8ms Nov 01 06:43:09 volumio volumio[897]: info: ------------------------------ 9ms Nov 01 06:43:09 volumio volumio[897]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 01 06:43:09 volumio volumio[897]: info: sendMpdCommand playlistinfo took 8 milliseconds Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:09 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:09 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"361 Kbps","isStreaming":false,"title":"04.So Danco Samba.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac","trackType":"flac"} Nov 01 06:43:09 volumio volumio[897]: verbose: CURRENT POSITION 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:09 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:09 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"361 Kbps","isStreaming":false,"title":"04.So Danco Samba.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac","trackType":"flac"} Nov 01 06:43:09 volumio volumio[897]: verbose: CURRENT POSITION 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:09 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:09 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:09 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:09 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:09 volumio volumio[897]: info: ------------------------------ 17ms Nov 01 06:43:09 volumio volumio[897]: info: ------------------------------ 18ms Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:09 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:12 volumio volumio[897]: info: CoreCommandRouter::volumioSeek Nov 01 06:43:12 volumio volumio[897]: info: CoreStateMachine::seek Nov 01 06:43:12 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:12 volumio volumio[897]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac","service":"mpd","name":"04.So Danco Samba.flac","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=563&web=//extralarge&path=%2FINTERNAL%2FAebersold_Jobim&metadata=false","duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Nov 01 06:43:12 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Nov 01 06:43:12 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:12 volumio volumio[897]: info: ControllerMpd::seek Nov 01 06:43:12 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:12 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:12 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:12 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:12 volumio volumio[897]: error: null Nov 01 06:43:12 volumio volumio[897]: info: Nov 01 06:43:12 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:12 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:12 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:12 volumio volumio[897]: info: Nov 01 06:43:12 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:12 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:12 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:12 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:12 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:12 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:12 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:12 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:12 volumio volumio[897]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 01 06:43:12 volumio volumio[897]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 01 06:43:12 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:12 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:12 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:12 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:12 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:12 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":126000,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"715 Kbps","isStreaming":false,"title":"04.So Danco Samba.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac","trackType":"flac"} Nov 01 06:43:12 volumio volumio[897]: verbose: CURRENT POSITION 3 Nov 01 06:43:12 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:12 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:12 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:12 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:12 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:12 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:12 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:12 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:12 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:12 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:12 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:12 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:12 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":126000,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"715 Kbps","isStreaming":false,"title":"04.So Danco Samba.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac","trackType":"flac"} Nov 01 06:43:12 volumio volumio[897]: verbose: CURRENT POSITION 3 Nov 01 06:43:12 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:12 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:12 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:12 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:12 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:12 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:12 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:12 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:12 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:12 volumio volumio[897]: info: ------------------------------ 16ms Nov 01 06:43:12 volumio volumio[897]: info: ------------------------------ 18ms Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:12 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:13 volumio volumio[897]: info: CoreCommandRouter::volumioSeek Nov 01 06:43:13 volumio volumio[897]: info: CoreStateMachine::seek Nov 01 06:43:13 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:13 volumio volumio[897]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac","service":"mpd","name":"04.So Danco Samba.flac","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=563&web=//extralarge&path=%2FINTERNAL%2FAebersold_Jobim&metadata=false","duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Nov 01 06:43:13 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Nov 01 06:43:13 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:13 volumio volumio[897]: info: ControllerMpd::seek Nov 01 06:43:13 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:13 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:13 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:13 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:13 volumio volumio[897]: error: null Nov 01 06:43:13 volumio volumio[897]: info: Nov 01 06:43:13 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:13 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:13 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:13 volumio volumio[897]: info: Nov 01 06:43:13 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:13 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:13 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:13 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:13 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:13 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:13 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:13 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:13 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:13 volumio volumio[897]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 01 06:43:13 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:13 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:13 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:13 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:13 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":227000,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"856 Kbps","isStreaming":false,"title":"04.So Danco Samba.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac","trackType":"flac"} Nov 01 06:43:13 volumio volumio[897]: verbose: CURRENT POSITION 3 Nov 01 06:43:13 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:13 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:13 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:13 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:13 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:13 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:13 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:13 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:13 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:13 volumio volumio[897]: info: ------------------------------ 6ms Nov 01 06:43:13 volumio volumio[897]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 01 06:43:13 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:13 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:13 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:13 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:13 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":227000,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"856 Kbps","isStreaming":false,"title":"04.So Danco Samba.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac","trackType":"flac"} Nov 01 06:43:13 volumio volumio[897]: verbose: CURRENT POSITION 3 Nov 01 06:43:13 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:13 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:13 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:13 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:13 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:13 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:13 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:13 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:13 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:13 volumio volumio[897]: info: ------------------------------ 11ms Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:13 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioSeek Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::seek Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:15 volumio volumio[897]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac","service":"mpd","name":"04.So Danco Samba.flac","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=563&web=//extralarge&path=%2FINTERNAL%2FAebersold_Jobim&metadata=false","duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::seek Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: error: null Nov 01 06:43:15 volumio volumio[897]: info: Nov 01 06:43:15 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:15 volumio volumio[897]: info: Nov 01 06:43:15 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:15 volumio volumio[897]: info: sendMpdCommand status took 0 milliseconds Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:15 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:15 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:15 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":281000,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"757 Kbps","isStreaming":false,"title":"04.So Danco Samba.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac","trackType":"flac"} Nov 01 06:43:15 volumio volumio[897]: verbose: CURRENT POSITION 3 Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:15 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:15 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":281000,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"757 Kbps","isStreaming":false,"title":"04.So Danco Samba.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac","trackType":"flac"} Nov 01 06:43:15 volumio volumio[897]: verbose: CURRENT POSITION 3 Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:15 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: ------------------------------ 11ms Nov 01 06:43:15 volumio volumio[897]: info: ------------------------------ 12ms Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioNext Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::next Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::stop Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::stPlaybackTimer Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::serviceStop Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 3 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::serviceStop Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::stop Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: info: Nov 01 06:43:15 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:15 volumio volumio[897]: info: sendMpdCommand stop took 2 milliseconds Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::play index undefined Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Nov 01 06:43:15 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:15 volumio volumio[897]: info: sendMpdCommand stop took 0 milliseconds Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand clear Nov 01 06:43:15 volumio volumio[897]: info: Nov 01 06:43:15 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:15 volumio volumio[897]: info: Nov 01 06:43:15 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:43:15 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:43:15 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 01 06:43:15 volumio volumio[897]: info: sendMpdCommand clear took 1 milliseconds Nov 01 06:43:15 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac" Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04.So Danco Samba.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/04.So Danco Samba.flac","trackType":"flac"} Nov 01 06:43:15 volumio volumio[897]: verbose: CURRENT POSITION 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::syncState stateService stop Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: No code Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 01 06:43:15 volumio volumio[897]: verbose: CURRENT POSITION 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::syncState stateService stop Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: No code Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: ------------------------------ 13ms Nov 01 06:43:15 volumio volumio[897]: info: ------------------------------ 13ms Nov 01 06:43:15 volumio volumio[897]: info: Nov 01 06:43:15 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:43:15 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:43:15 volumio volumio[897]: error: updateQueue error: null Nov 01 06:43:15 volumio volumio[897]: info: Nov 01 06:43:15 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:43:15 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:43:15 volumio volumio[897]: info: ------------------------------ 12ms Nov 01 06:43:15 volumio volumio[897]: info: sendMpdCommand add "INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac" took 12 milliseconds Nov 01 06:43:15 volumio volumio[897]: info: ------------------------------ 0ms Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand play Nov 01 06:43:15 volumio volumio[897]: info: Nov 01 06:43:15 volumio volumio[897]: ---------------------------- MPD announces system playlist update Nov 01 06:43:15 volumio volumio[897]: info: Ignoring MPD Status Update Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: info: ------------------------------ 7ms Nov 01 06:43:15 volumio volumio[897]: info: sendMpdCommand play took 7 milliseconds Nov 01 06:43:15 volumio volumio[897]: info: ------------------------------ 6ms Nov 01 06:43:15 volumio volumio[897]: info: Nov 01 06:43:15 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:15 volumio volumio[897]: info: Nov 01 06:43:15 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:15 volumio volumio[897]: info: Nov 01 06:43:15 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:15 volumio volumio[897]: info: sendMpdCommand status took 2 milliseconds Nov 01 06:43:15 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:15 volumio volumio[897]: info: Nov 01 06:43:15 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:15 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05.O Grande Amor.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac","trackType":"flac"} Nov 01 06:43:15 volumio volumio[897]: verbose: CURRENT POSITION 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05.O Grande Amor.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac","trackType":"flac"} Nov 01 06:43:15 volumio volumio[897]: verbose: CURRENT POSITION 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:15 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":364,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"546 Kbps","isStreaming":false,"title":"05.O Grande Amor.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac","trackType":"flac"} Nov 01 06:43:15 volumio volumio[897]: verbose: CURRENT POSITION 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:15 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: ------------------------------ 6ms Nov 01 06:43:15 volumio volumio[897]: info: ------------------------------ 12ms Nov 01 06:43:15 volumio volumio[897]: info: ------------------------------ 11ms Nov 01 06:43:15 volumio volumio[897]: info: sendMpdCommand status took 10 milliseconds Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 01 06:43:15 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:15 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"428 Kbps","isStreaming":false,"title":"05.O Grande Amor.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac","trackType":"flac"} Nov 01 06:43:15 volumio volumio[897]: verbose: CURRENT POSITION 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:15 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:15 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:15 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:15 volumio volumio[897]: info: ------------------------------ 27ms Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:15 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:17 volumio volumio[897]: info: CoreCommandRouter::volumioSeek Nov 01 06:43:17 volumio volumio[897]: info: CoreStateMachine::seek Nov 01 06:43:17 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:17 volumio volumio[897]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac","service":"mpd","name":"05.O Grande Amor.flac","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=563&web=//extralarge&path=%2FINTERNAL%2FAebersold_Jobim&metadata=false","duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Nov 01 06:43:17 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Nov 01 06:43:17 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:17 volumio volumio[897]: info: ControllerMpd::seek Nov 01 06:43:17 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:17 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:17 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:17 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:17 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:17 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:17 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:17 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:17 volumio volumio[897]: error: null Nov 01 06:43:17 volumio volumio[897]: info: Nov 01 06:43:17 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:17 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:17 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:17 volumio volumio[897]: info: Nov 01 06:43:17 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:17 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:17 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:17 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:17 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:17 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:17 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:17 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:17 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:17 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 01 06:43:17 volumio volumio[897]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 01 06:43:17 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:17 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:17 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:17 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:17 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":156000,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"693 Kbps","isStreaming":false,"title":"05.O Grande Amor.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac","trackType":"flac"} Nov 01 06:43:17 volumio volumio[897]: verbose: CURRENT POSITION 4 Nov 01 06:43:17 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:17 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:17 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:17 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:17 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:17 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:17 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:17 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:17 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:17 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:17 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:17 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:17 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":156866,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"545 Kbps","isStreaming":false,"title":"05.O Grande Amor.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac","trackType":"flac"} Nov 01 06:43:17 volumio volumio[897]: verbose: CURRENT POSITION 4 Nov 01 06:43:17 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:17 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:17 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:17 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:17 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:17 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:17 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:17 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:17 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:17 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:17 volumio volumio[897]: info: ------------------------------ 13ms Nov 01 06:43:17 volumio volumio[897]: info: ------------------------------ 13ms Nov 01 06:43:18 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:18 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:18 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:18 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:18 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:18 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:18 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:18 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:18 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:18 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:18 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:18 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:18 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:18 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:18 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:18 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:19 volumio volumio[897]: info: CoreCommandRouter::volumioSeek Nov 01 06:43:19 volumio volumio[897]: info: CoreStateMachine::seek Nov 01 06:43:19 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:19 volumio volumio[897]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac","service":"mpd","name":"05.O Grande Amor.flac","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=563&web=//extralarge&path=%2FINTERNAL%2FAebersold_Jobim&metadata=false","duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Nov 01 06:43:19 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Nov 01 06:43:19 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:19 volumio volumio[897]: info: ControllerMpd::seek Nov 01 06:43:19 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:19 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:19 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:19 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:19 volumio volumio[897]: error: null Nov 01 06:43:19 volumio volumio[897]: info: Nov 01 06:43:19 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:19 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:19 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:19 volumio volumio[897]: info: Nov 01 06:43:19 volumio volumio[897]: ---------------------------- MPD announces state update: player Nov 01 06:43:19 volumio volumio[897]: info: ControllerMpd::getState Nov 01 06:43:19 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Nov 01 06:43:19 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Nov 01 06:43:19 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:19 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:19 volumio volumio[897]: info: sendMpdCommand status took 3 milliseconds Nov 01 06:43:19 volumio volumio[897]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 01 06:43:19 volumio volumio[897]: verbose: ControllerMpd::parseState Nov 01 06:43:19 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 06:43:19 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:19 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:19 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:19 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:19 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":179000,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2196 Kbps","isStreaming":false,"title":"05.O Grande Amor.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac","trackType":"flac"} Nov 01 06:43:19 volumio volumio[897]: verbose: CURRENT POSITION 4 Nov 01 06:43:19 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:19 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:19 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:19 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:19 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:19 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:19 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:19 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:19 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:19 volumio volumio[897]: info: ------------------------------ 7ms Nov 01 06:43:19 volumio volumio[897]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 01 06:43:19 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Nov 01 06:43:19 volumio volumio[897]: info: ControllerMpd::pushState Nov 01 06:43:19 volumio volumio[897]: info: CoreCommandRouter::servicePushState Nov 01 06:43:19 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:19 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":179000,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05.O Grande Amor.flac","artist":null,"album":null,"uri":"INTERNAL/Aebersold_Jobim/05.O Grande Amor.flac","trackType":"flac"} Nov 01 06:43:19 volumio volumio[897]: verbose: CURRENT POSITION 4 Nov 01 06:43:19 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Nov 01 06:43:19 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Nov 01 06:43:19 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Nov 01 06:43:19 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:19 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:19 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:19 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:19 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:19 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:19 volumio volumio[897]: info: ------------------------------ 15ms Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:19 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:22 volumio volumio[897]: info: Preload queue cleared Nov 01 06:43:22 volumio volumio[897]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 01 06:43:22 volumio volumio[897]: info: CoreStateMachine::ClearQueue Nov 01 06:43:22 volumio volumio[897]: info: CoreStateMachine::stop Nov 01 06:43:22 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 06:43:22 volumio volumio[897]: info: CoreStateMachine::stPlaybackTimer Nov 01 06:43:22 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Nov 01 06:43:22 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Nov 01 06:43:22 volumio volumio[897]: info: CoreStateMachine::pushState Nov 01 06:43:22 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:22 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 06:43:22 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Nov 01 06:43:22 volumio volumio[897]: info: CoreStateMachine::serviceStop Nov 01 06:43:22 volumio volumio[897]: info: CorePlayQueue::getTrack 4 Nov 01 06:43:22 volumio volumio[897]: info: CoreCommandRouter::serviceStop Nov 01 06:43:22 volumio volumio[897]: info: ControllerMpd::stop Nov 01 06:43:22 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 06:43:22 volumio volumio[897]: info: CorePlayQueue::clearPlayQueue Nov 01 06:43:22 volumio volumio[897]: info: CorePlayQueue::saveQueue Nov 01 06:43:22 volumio volumio[897]: info: CoreCommandRouter::volumioPushQueue Nov 01 06:43:22 volumio volumio[897]: info: CoreStateMachine::addQueueItems Nov 01 06:43:22 volumio volumio[897]: info: CorePlayQueue::addQueueItems Nov 01 06:43:22 volumio volumio[897]: info: Preload queue cleared Nov 01 06:43:22 volumio volumio[897]: info: Adding Item to queue: music-library/INTERNAL/ABC - Chario Demo CD (Audiophile Recordings) Nov 01 06:43:22 volumio volumio[897]: info: Exploding uri music-library/INTERNAL/ABC - Chario Demo CD (Audiophile Recordings) in service mpd Nov 01 06:43:22 volumio volumio[897]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 01 06:43:22 volumio volumio[897]: SPOTIFY: SPOTIFY VOLUME 74 Nov 01 06:43:22 volumio volumio[897]: SPOTIFY: VOLUMIO VOLUME 75 Nov 01 06:43:22 volumio volumio[897]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 06:43:22 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=Massenet/Chario%20Demo%20CD%20(Audiophile%20Recordings)/extralarge&path=%2Fmnt%2FINTERNAL%2FABC%20-%20Chario%20Demo%20CD%20(Audiophile%20Recordings)%2FCDimage.cue&metadata=false Nov 01 06:43:22 volumio volumio[897]: info: URI /mnt/INTERNAL/ABC - Chario Demo CD (Audiophile Recordings)/CDimage.cue Nov 01 06:43:22 volumio volumio[897]: info: ALBUMART /albumart?cacheid=563&web=Susan%20Osborn/Chario%20Demo%20CD%20(Audiophile%20Recordings)/extralarge&path=%2Fmnt%2FINTERNAL%2FABC%20-%20Chario%20Demo%20CD%20(Audiophile%20Recordings)%2FCDimage.cue&metadata=false Nov 01 06:43:22 volumio volumio[897]: info: URI /mnt/INTERNAL/ABC - Chario Demo CD (Audiophile Recordings)/CDimage.cue Nov 01 06:43:22 volumio volumio[897]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 06:43:22 volumio volumio[897]: Error: Unable to resolve or reject the same promise twice Nov 01 06:43:22 volumio volumio[897]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Nov 01 06:43:22 volumio volumio[897]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Nov 01 06:43:22 volumio volumio[897]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Nov 01 06:43:22 volumio volumio[897]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Nov 01 06:43:22 volumio volumio[897]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Nov 01 06:43:22 volumio volumio[897]: at Socket.emit (events.js:400:28) Nov 01 06:43:22 volumio volumio[897]: at addChunk (internal/streams/readable.js:293:12) Nov 01 06:43:22 volumio volumio[897]: at readableAddChunk (internal/streams/readable.js:263:11) Nov 01 06:43:22 volumio volumio[897]: at Socket.Readable.push (internal/streams/readable.js:206:10) Nov 01 06:43:22 volumio volumio[897]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Nov 01 06:43:22 volumio volumio[897]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 06:43:22 volumio sudo[2938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-01 06:42 Nov 01 06:43:22 volumio sudo[2938]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="9552505bba4239c163ac9ee7b0b87a56e0dd0574" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="3d3fc502273a5c173ba19f72c1a952bdb2bdd3b0" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 Feb 2024 07:28:53 PM CET" VOLUMIO_VERSION="3.616" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="f11d51aa83fa91106999927181e8c0f7"