-- Logs begin at Thu 2019-02-14 19:11:58 KST, end at Sat 2026-01-10 19:00:58 KST. -- Jan 10 18:59:24 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::ClearQueue Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::stop Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::stPlaybackTimer Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::serviceStop Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::serviceStop Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::stop Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::clearPlayQueue Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::addQueueItems Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::addQueueItems Jan 10 18:59:24 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 18:59:24 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Aaron Neville - When A Man Loves A Woman.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Aaron Neville - When A Man Loves A Woman.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Andrea Bocelli - Besame Mucho.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Andrea Bocelli - Besame Mucho.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Benito Madonia - Antonio Forcione - Caruso.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Benito Madonia - Antonio Forcione - Caruso.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Chie Ayado - I Got A Name.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Chie Ayado - I Got A Name.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPlay Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::play index 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::addQueueItems Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::addQueueItems Jan 10 18:59:24 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 18:59:24 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Hank Williams - I'm So Lonesome I Could Cry.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Hank Williams - I'm So Lonesome I Could Cry.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Louis Armstrong - What A Wonderful World.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Louis Armstrong - What A Wonderful World.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Meta Roos - Enjoy The Rhythm.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Meta Roos - Enjoy The Rhythm.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Olivia Newton-JohnHonestly Love You.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Olivia Newton-JohnHonestly Love You.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Ray Charles - Fever.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Ray Charles - Fever.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/RUA - Hallelujah.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/RUA - Hallelujah.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Simone Kopmajer - Just the Way You Are.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Simone Kopmajer - Just the Way You Are.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Sov Du Eller - Kari Bremnes.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Sov Du Eller - Kari Bremnes.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Susan Wong - Imagine.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Susan Wong - Imagine.wav Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::stop Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::play index undefined Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::startPlaybackTimer Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::clearAddPlayTracks USB/Appreciate/4. Jazz & Blues/V.A/Chie Ayado - I Got A Name.wav Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 18:59:24 top-core-1 volumio[807]: info: Jan 10 18:59:24 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand stop took 21 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand stop took 3 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 18:59:24 top-core-1 volumio[807]: info: Jan 10 18:59:24 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:24 top-core-1 volumio[807]: info: Jan 10 18:59:24 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:24 top-core-1 volumio[807]: info: Jan 10 18:59:24 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 18:59:24 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand status took 3 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand clear took 2 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand status took 2 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand status took 2 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand add "USB/Appreciate/4. Jazz & Blues/V.A/Chie Ayado - I Got A Name.wav" Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 10 18:59:24 top-core-1 volumio[807]: verbose: CURRENT POSITION 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService stop Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: No code Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 10 18:59:24 top-core-1 volumio[807]: verbose: CURRENT POSITION 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService stop Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: No code Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: ------------------------------ 36ms Jan 10 18:59:24 top-core-1 volumio[807]: info: ------------------------------ 36ms Jan 10 18:59:24 top-core-1 volumio[807]: info: Jan 10 18:59:24 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 18:59:24 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 18:59:24 top-core-1 volumio[807]: info: Jan 10 18:59:24 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 18:59:24 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 18:59:24 top-core-1 volumio[807]: error: updateQueue error: null Jan 10 18:59:24 top-core-1 volumio[807]: info: Jan 10 18:59:24 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 18:59:24 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 18:59:24 top-core-1 volumio[807]: info: ------------------------------ 39ms Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 38 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand add "USB/Appreciate/4. Jazz & Blues/V.A/Chie Ayado - I Got A Name.wav" took 38 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: info: ------------------------------ 3ms Jan 10 18:59:24 top-core-1 volumio[807]: info: ------------------------------ 3ms Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand play Jan 10 18:59:24 top-core-1 volumio[807]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 10 18:59:24 top-core-1 volumio[807]: info: ------------------------------ 43ms Jan 10 18:59:24 top-core-1 volumio[807]: info: Jan 10 18:59:24 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 18:59:24 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 18:59:24 top-core-1 volumio[807]: info: Jan 10 18:59:24 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 18:59:24 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 18:59:24 top-core-1 volumio[807]: info: Jan 10 18:59:24 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:24 top-core-1 volumio[807]: info: ------------------------------ 9ms Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand play took 7 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: info: ------------------------------ 6ms Jan 10 18:59:24 top-core-1 volumio[807]: info: ------------------------------ 7ms Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:24 top-core-1 volumio[807]: info: Jan 10 18:59:24 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:24 top-core-1 volumio[807]: info: Jan 10 18:59:24 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:24 top-core-1 volumio[807]: info: Jan 10 18:59:24 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand status took 4 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand status took 4 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand status took 4 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:59:24 top-core-1 volumio[807]: info: Jan 10 18:59:24 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:24 top-core-1 volumio[807]: info: Jan 10 18:59:24 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand status took 6 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand status took 4 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand status took 3 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chie Ayado - I Got A Name.wav","artist":null,"album":null,"uri":"USB/Appreciate/4. Jazz & Blues/V.A/Chie Ayado - I Got A Name.wav","trackType":"wav"} Jan 10 18:59:24 top-core-1 volumio[807]: verbose: CURRENT POSITION 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chie Ayado - I Got A Name.wav","artist":null,"album":null,"uri":"USB/Appreciate/4. Jazz & Blues/V.A/Chie Ayado - I Got A Name.wav","trackType":"wav"} Jan 10 18:59:24 top-core-1 volumio[807]: verbose: CURRENT POSITION 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:59:24 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chie Ayado - I Got A Name.wav","artist":null,"album":null,"uri":"USB/Appreciate/4. Jazz & Blues/V.A/Chie Ayado - I Got A Name.wav","trackType":"wav"} Jan 10 18:59:24 top-core-1 volumio[807]: verbose: CURRENT POSITION 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:59:24 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: ------------------------------ 38ms Jan 10 18:59:24 top-core-1 volumio[807]: info: ------------------------------ 60ms Jan 10 18:59:24 top-core-1 volumio[807]: info: ------------------------------ 60ms Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 50 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 49 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 49 milliseconds Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:59:24 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chie Ayado - I Got A Name.wav","artist":null,"album":null,"uri":"USB/Appreciate/4. Jazz & Blues/V.A/Chie Ayado - I Got A Name.wav","trackType":"wav"} Jan 10 18:59:24 top-core-1 volumio[807]: verbose: CURRENT POSITION 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:59:24 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chie Ayado - I Got A Name.wav","artist":null,"album":null,"uri":"USB/Appreciate/4. Jazz & Blues/V.A/Chie Ayado - I Got A Name.wav","trackType":"wav"} Jan 10 18:59:24 top-core-1 volumio[807]: verbose: CURRENT POSITION 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:59:24 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chie Ayado - I Got A Name.wav","artist":null,"album":null,"uri":"USB/Appreciate/4. Jazz & Blues/V.A/Chie Ayado - I Got A Name.wav","trackType":"wav"} Jan 10 18:59:24 top-core-1 volumio[807]: verbose: CURRENT POSITION 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:59:24 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:24 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:24 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:24 top-core-1 volumio[807]: info: ------------------------------ 96ms Jan 10 18:59:24 top-core-1 volumio[807]: info: ------------------------------ 95ms Jan 10 18:59:24 top-core-1 volumio[807]: info: ------------------------------ 94ms Jan 10 18:59:26 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::ClearQueue Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::stop Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::stPlaybackTimer Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 18:59:26 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:26 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::serviceStop Jan 10 18:59:26 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreCommandRouter::serviceStop Jan 10 18:59:26 top-core-1 volumio[807]: info: ControllerMpd::stop Jan 10 18:59:26 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 18:59:26 top-core-1 volumio[807]: info: CorePlayQueue::clearPlayQueue Jan 10 18:59:26 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::addQueueItems Jan 10 18:59:26 top-core-1 volumio[807]: info: CorePlayQueue::addQueueItems Jan 10 18:59:26 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 18:59:26 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Aaron Neville - When A Man Loves A Woman.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Aaron Neville - When A Man Loves A Woman.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Andrea Bocelli - Besame Mucho.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Andrea Bocelli - Besame Mucho.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Benito Madonia - Antonio Forcione - Caruso.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Benito Madonia - Antonio Forcione - Caruso.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 18:59:26 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 18:59:26 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPlay Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::play index 2 Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::addQueueItems Jan 10 18:59:26 top-core-1 volumio[807]: info: CorePlayQueue::addQueueItems Jan 10 18:59:26 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 18:59:26 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Chie Ayado - I Got A Name.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Chie Ayado - I Got A Name.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Hank Williams - I'm So Lonesome I Could Cry.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Hank Williams - I'm So Lonesome I Could Cry.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Louis Armstrong - What A Wonderful World.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Louis Armstrong - What A Wonderful World.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Meta Roos - Enjoy The Rhythm.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Meta Roos - Enjoy The Rhythm.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Olivia Newton-JohnHonestly Love You.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Olivia Newton-JohnHonestly Love You.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Ray Charles - Fever.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Ray Charles - Fever.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/RUA - Hallelujah.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/RUA - Hallelujah.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Simone Kopmajer - Just the Way You Are.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Simone Kopmajer - Just the Way You Are.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Sov Du Eller - Kari Bremnes.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Sov Du Eller - Kari Bremnes.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Susan Wong - Imagine.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/V.A/Susan Wong - Imagine.wav Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::stop Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 18:59:26 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::play index undefined Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 18:59:26 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 18:59:26 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:26 top-core-1 volumio[807]: info: CoreStateMachine::startPlaybackTimer Jan 10 18:59:26 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:26 top-core-1 volumio[807]: verbose: ControllerMpd::clearAddPlayTracks USB/Appreciate/4. Jazz & Blues/V.A/Benito Madonia - Antonio Forcione - Caruso.wav Jan 10 18:59:26 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 18:59:26 top-core-1 volumio[807]: info: Jan 10 18:59:26 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:26 top-core-1 volumio[807]: info: sendMpdCommand stop took 22 milliseconds Jan 10 18:59:26 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:26 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:26 top-core-1 volumio[807]: info: sendMpdCommand stop took 3 milliseconds Jan 10 18:59:26 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 18:59:26 top-core-1 volumio[807]: info: Jan 10 18:59:26 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:26 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:27 top-core-1 volumio[807]: info: Jan 10 18:59:27 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:27 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:27 top-core-1 volumio[807]: info: Jan 10 18:59:27 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 18:59:27 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand status took 3 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand clear took 2 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand status took 1 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand status took 2 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand add "USB/Appreciate/4. Jazz & Blues/V.A/Benito Madonia - Antonio Forcione - Caruso.wav" Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:27 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 10 18:59:27 top-core-1 volumio[807]: verbose: CURRENT POSITION 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService stop Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:27 top-core-1 volumio[807]: info: No code Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:27 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 10 18:59:27 top-core-1 volumio[807]: verbose: CURRENT POSITION 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService stop Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:27 top-core-1 volumio[807]: info: No code Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:27 top-core-1 volumio[807]: info: ------------------------------ 35ms Jan 10 18:59:27 top-core-1 volumio[807]: info: ------------------------------ 34ms Jan 10 18:59:27 top-core-1 volumio[807]: info: Jan 10 18:59:27 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 18:59:27 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 18:59:27 top-core-1 volumio[807]: info: Jan 10 18:59:27 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 18:59:27 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 18:59:27 top-core-1 volumio[807]: error: updateQueue error: null Jan 10 18:59:27 top-core-1 volumio[807]: info: Jan 10 18:59:27 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 18:59:27 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 18:59:27 top-core-1 volumio[807]: info: ------------------------------ 37ms Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 36 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand add "USB/Appreciate/4. Jazz & Blues/V.A/Benito Madonia - Antonio Forcione - Caruso.wav" took 36 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: info: ------------------------------ 3ms Jan 10 18:59:27 top-core-1 volumio[807]: info: ------------------------------ 3ms Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand play Jan 10 18:59:27 top-core-1 volumio[807]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 10 18:59:27 top-core-1 volumio[807]: info: ------------------------------ 43ms Jan 10 18:59:27 top-core-1 volumio[807]: info: Jan 10 18:59:27 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 18:59:27 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 18:59:27 top-core-1 volumio[807]: info: Jan 10 18:59:27 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 18:59:27 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 18:59:27 top-core-1 volumio[807]: info: Jan 10 18:59:27 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:27 top-core-1 volumio[807]: info: Jan 10 18:59:27 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:27 top-core-1 volumio[807]: info: ------------------------------ 19ms Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand play took 16 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: info: ------------------------------ 15ms Jan 10 18:59:27 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:27 top-core-1 volumio[807]: info: ------------------------------ 14ms Jan 10 18:59:27 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:27 top-core-1 volumio[807]: info: Jan 10 18:59:27 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:27 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:27 top-core-1 volumio[807]: info: Jan 10 18:59:27 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:27 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:27 top-core-1 volumio[807]: info: Jan 10 18:59:27 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:27 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:27 top-core-1 volumio[807]: info: Jan 10 18:59:27 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 18:59:27 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand status took 27 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand status took 27 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand status took 27 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand status took 26 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand status took 10 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand status took 9 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:59:27 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Benito Madonia - Antonio Forcione - Caruso.wav","artist":null,"album":null,"uri":"USB/Appreciate/4. Jazz & Blues/V.A/Benito Madonia - Antonio Forcione - Caruso.wav","trackType":"wav"} Jan 10 18:59:27 top-core-1 volumio[807]: verbose: CURRENT POSITION 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 18:59:27 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Benito Madonia - Antonio Forcione - Caruso.wav","artist":null,"album":null,"uri":"USB/Appreciate/4. Jazz & Blues/V.A/Benito Madonia - Antonio Forcione - Caruso.wav","trackType":"wav"} Jan 10 18:59:27 top-core-1 volumio[807]: verbose: CURRENT POSITION 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:59:27 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:27 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Benito Madonia - Antonio Forcione - Caruso.wav","artist":null,"album":null,"uri":"USB/Appreciate/4. Jazz & Blues/V.A/Benito Madonia - Antonio Forcione - Caruso.wav","trackType":"wav"} Jan 10 18:59:27 top-core-1 volumio[807]: verbose: CURRENT POSITION 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:59:27 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:27 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Benito Madonia - Antonio Forcione - Caruso.wav","artist":null,"album":null,"uri":"USB/Appreciate/4. Jazz & Blues/V.A/Benito Madonia - Antonio Forcione - Caruso.wav","trackType":"wav"} Jan 10 18:59:27 top-core-1 volumio[807]: verbose: CURRENT POSITION 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:59:27 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:27 top-core-1 volumio[807]: info: ------------------------------ 64ms Jan 10 18:59:27 top-core-1 volumio[807]: info: ------------------------------ 89ms Jan 10 18:59:27 top-core-1 volumio[807]: info: ------------------------------ 88ms Jan 10 18:59:27 top-core-1 volumio[807]: info: ------------------------------ 87ms Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 64 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 63 milliseconds Jan 10 18:59:27 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 18:59:27 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Benito Madonia - Antonio Forcione - Caruso.wav","artist":null,"album":null,"uri":"USB/Appreciate/4. Jazz & Blues/V.A/Benito Madonia - Antonio Forcione - Caruso.wav","trackType":"wav"} Jan 10 18:59:27 top-core-1 volumio[807]: verbose: CURRENT POSITION 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:59:27 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:27 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Benito Madonia - Antonio Forcione - Caruso.wav","artist":null,"album":null,"uri":"USB/Appreciate/4. Jazz & Blues/V.A/Benito Madonia - Antonio Forcione - Caruso.wav","trackType":"wav"} Jan 10 18:59:27 top-core-1 volumio[807]: verbose: CURRENT POSITION 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 18:59:27 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 18:59:27 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 18:59:27 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 18:59:27 top-core-1 volumio[807]: info: ------------------------------ 107ms Jan 10 18:59:27 top-core-1 volumio[807]: info: ------------------------------ 107ms Jan 10 18:59:42 top-core-1 volumiologrotate[623]: ls: cannot access '/var/log/samba/log.wb-TOP': No such file or directory Jan 10 18:59:42 top-core-1 volumiologrotate[623]: ls: cannot access 'CORE': No such file or directory Jan 10 18:59:42 top-core-1 volumiologrotate[623]: ls: cannot access '1': No such file or directory Jan 10 19:00:01 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 19:00:01 top-core-1 volumio[807]: info: CURURI: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』 Jan 10 19:00:02 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 19:00:02 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/01 - Starry Starry Night.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/02 - Fields of Gold.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/03 - Somewhere My Love.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/04 - Make Believe It's Your First Time.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/05 - Dreaming With You.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/06 - Angle.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/07 - A Living Prayer.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/08 - Longer.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/09 - Unchained Melody.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/10 - It's Doesn't Matter.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/11 - Last Christmas.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/12 - Just When I Needed You Most.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/13 - Speak Softly Love.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/14 - I Cry.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/01 - Starry Starry Night.wav in service mpd Jan 10 19:00:02 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Yao%20Si%20Ting%20/Angle/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FYao%20Si%20Ting%20%E3%80%8EAngle%E3%80%8F%2F01%20-%20Starry%20Starry%20Night.wav&metadata=false Jan 10 19:00:02 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/01 - Starry Starry Night.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/02 - Fields of Gold.wav in service mpd Jan 10 19:00:02 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Yao%20Si%20Ting%20/Angle/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FYao%20Si%20Ting%20%E3%80%8EAngle%E3%80%8F%2F02%20-%20Fields%20of%20Gold.wav&metadata=false Jan 10 19:00:02 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/02 - Fields of Gold.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::ClearQueue Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::stop Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::stPlaybackTimer Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::serviceStop Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 2 Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::serviceStop Jan 10 19:00:02 top-core-1 volumio[807]: info: ControllerMpd::stop Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::clearPlayQueue Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::addQueueItems Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::addQueueItems Jan 10 19:00:02 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 19:00:02 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/01 - Starry Starry Night.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/01 - Starry Starry Night.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/02 - Fields of Gold.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/02 - Fields of Gold.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/03 - Somewhere My Love.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/03 - Somewhere My Love.wav in service mpd Jan 10 19:00:02 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/04 - Make Believe It's Your First Time.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/04 - Make Believe It's Your First Time.wav in service mpd Jan 10 19:00:02 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/05 - Dreaming With You.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/05 - Dreaming With You.wav in service mpd Jan 10 19:00:02 top-core-1 volumio[807]: info: Jan 10 19:00:02 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:02 top-core-1 volumio[807]: info: sendMpdCommand stop took 14 milliseconds Jan 10 19:00:02 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:02 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Yao%20Si%20Ting%20/Angle/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FYao%20Si%20Ting%20%E3%80%8EAngle%E3%80%8F%2F03%20-%20Somewhere%20My%20Love.wav&metadata=false Jan 10 19:00:02 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/03 - Somewhere My Love.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Yao%20Si%20Ting%20/Angle/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FYao%20Si%20Ting%20%E3%80%8EAngle%E3%80%8F%2F04%20-%20Make%20Believe%20It's%20Your%20First%20Time.wav&metadata=false Jan 10 19:00:02 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/04 - Make Believe It's Your First Time.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Yao%20Si%20Ting%20/Angle/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FYao%20Si%20Ting%20%E3%80%8EAngle%E3%80%8F%2F05%20-%20Dreaming%20With%20You.wav&metadata=false Jan 10 19:00:02 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/05 - Dreaming With You.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPlay Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::play index 4 Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::addQueueItems Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::addQueueItems Jan 10 19:00:02 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 19:00:02 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/06 - Angle.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/06 - Angle.wav in service mpd Jan 10 19:00:02 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/07 - A Living Prayer.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/07 - A Living Prayer.wav in service mpd Jan 10 19:00:02 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/08 - Longer.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/08 - Longer.wav in service mpd Jan 10 19:00:02 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/09 - Unchained Melody.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/09 - Unchained Melody.wav in service mpd Jan 10 19:00:02 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/10 - It's Doesn't Matter.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/10 - It's Doesn't Matter.wav in service mpd Jan 10 19:00:02 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/11 - Last Christmas.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/11 - Last Christmas.wav in service mpd Jan 10 19:00:02 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/12 - Just When I Needed You Most.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/12 - Just When I Needed You Most.wav in service mpd Jan 10 19:00:02 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/13 - Speak Softly Love.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/13 - Speak Softly Love.wav in service mpd Jan 10 19:00:02 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/14 - I Cry.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/14 - I Cry.wav in service mpd Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::stop Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::play index undefined Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 4 Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::startPlaybackTimer Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 4 Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::clearAddPlayTracks USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/05 - Dreaming With You.wav Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 19:00:02 top-core-1 volumio[807]: info: Jan 10 19:00:02 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:02 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:02 top-core-1 volumio[807]: info: Jan 10 19:00:02 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:02 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:02 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Yao%20Si%20Ting%20/Angle/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FYao%20Si%20Ting%20%E3%80%8EAngle%E3%80%8F%2F06%20-%20Angle.wav&metadata=false Jan 10 19:00:02 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/06 - Angle.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Yao%20Si%20Ting%20/Angle/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FYao%20Si%20Ting%20%E3%80%8EAngle%E3%80%8F%2F07%20-%20A%20Living%20Prayer.wav&metadata=false Jan 10 19:00:02 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/07 - A Living Prayer.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Yao%20Si%20Ting%20/Angle/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FYao%20Si%20Ting%20%E3%80%8EAngle%E3%80%8F%2F08%20-%20Longer.wav&metadata=false Jan 10 19:00:02 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/08 - Longer.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Yao%20Si%20Ting%20/Angle/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FYao%20Si%20Ting%20%E3%80%8EAngle%E3%80%8F%2F09%20-%20Unchained%20Melody.wav&metadata=false Jan 10 19:00:02 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/09 - Unchained Melody.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Yao%20Si%20Ting%20/Angle/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FYao%20Si%20Ting%20%E3%80%8EAngle%E3%80%8F%2F10%20-%20It's%20Doesn't%20Matter.wav&metadata=false Jan 10 19:00:02 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/10 - It's Doesn't Matter.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Yao%20Si%20Ting%20/Angle/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FYao%20Si%20Ting%20%E3%80%8EAngle%E3%80%8F%2F11%20-%20Last%20Christmas.wav&metadata=false Jan 10 19:00:02 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/11 - Last Christmas.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Yao%20Si%20Ting%20/Angle/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FYao%20Si%20Ting%20%E3%80%8EAngle%E3%80%8F%2F12%20-%20Just%20When%20I%20Needed%20You%20Most.wav&metadata=false Jan 10 19:00:02 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/12 - Just When I Needed You Most.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Yao%20Si%20Ting%20/Angle/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FYao%20Si%20Ting%20%E3%80%8EAngle%E3%80%8F%2F13%20-%20Speak%20Softly%20Love.wav&metadata=false Jan 10 19:00:02 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/13 - Speak Softly Love.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Yao%20Si%20Ting%20/Angle/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FYao%20Si%20Ting%20%E3%80%8EAngle%E3%80%8F%2F14%20-%20I%20Cry.wav&metadata=false Jan 10 19:00:02 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/14 - I Cry.wav Jan 10 19:00:02 top-core-1 volumio[807]: info: sendMpdCommand status took 21 milliseconds Jan 10 19:00:02 top-core-1 volumio[807]: info: sendMpdCommand stop took 5 milliseconds Jan 10 19:00:02 top-core-1 volumio[807]: info: sendMpdCommand status took 4 milliseconds Jan 10 19:00:02 top-core-1 volumio[807]: info: sendMpdCommand status took 4 milliseconds Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 19:00:02 top-core-1 volumio[807]: info: Jan 10 19:00:02 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:02 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:02 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 10 19:00:02 top-core-1 volumio[807]: info: sendMpdCommand clear took 15 milliseconds Jan 10 19:00:02 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 10 19:00:02 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand add "USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/05 - Dreaming With You.wav" Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:02 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 4 Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 4 Jan 10 19:00:02 top-core-1 volumio[807]: 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":"Benito Madonia - Antonio Forcione - Caruso.wav","artist":null,"album":null,"uri":"USB/Appreciate/4. Jazz & Blues/V.A/Benito Madonia - Antonio Forcione - Caruso.wav","trackType":"wav"} Jan 10 19:00:02 top-core-1 volumio[807]: verbose: CURRENT POSITION 4 Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService stop Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 4 Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:02 top-core-1 volumio[807]: info: No code Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 4 Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:02 top-core-1 volumio[807]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 10 19:00:02 top-core-1 volumio[807]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 10 19:00:02 top-core-1 volumio[807]: info: ------------------------------ 60ms Jan 10 19:00:02 top-core-1 volumio[807]: info: ------------------------------ 43ms Jan 10 19:00:02 top-core-1 volumio[807]: info: ------------------------------ 44ms Jan 10 19:00:02 top-core-1 volumio[807]: info: Jan 10 19:00:02 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:02 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:02 top-core-1 volumio[807]: info: Jan 10 19:00:02 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:02 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:02 top-core-1 volumio[807]: error: updateQueue error: null Jan 10 19:00:02 top-core-1 volumio[807]: info: Jan 10 19:00:02 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:02 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:02 top-core-1 volumio[807]: info: ------------------------------ 27ms Jan 10 19:00:02 top-core-1 volumio[807]: info: sendMpdCommand add "USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/05 - Dreaming With You.wav" took 26 milliseconds Jan 10 19:00:02 top-core-1 volumio[807]: info: ------------------------------ 3ms Jan 10 19:00:02 top-core-1 volumio[807]: info: ------------------------------ 3ms Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand play Jan 10 19:00:02 top-core-1 volumio[807]: info: Jan 10 19:00:02 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:02 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:02 top-core-1 volumio[807]: info: Jan 10 19:00:02 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:02 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:02 top-core-1 volumio[807]: info: ------------------------------ 11ms Jan 10 19:00:02 top-core-1 volumio[807]: info: sendMpdCommand play took 10 milliseconds Jan 10 19:00:02 top-core-1 volumio[807]: info: Jan 10 19:00:02 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:02 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:02 top-core-1 volumio[807]: info: Jan 10 19:00:02 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:02 top-core-1 volumio[807]: info: ------------------------------ 13ms Jan 10 19:00:02 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:02 top-core-1 volumio[807]: info: ------------------------------ 12ms Jan 10 19:00:02 top-core-1 volumio[807]: info: Jan 10 19:00:02 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:02 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:02 top-core-1 volumio[807]: info: sendMpdCommand status took 6 milliseconds Jan 10 19:00:02 top-core-1 volumio[807]: info: sendMpdCommand status took 5 milliseconds Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:02 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 10 19:00:02 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 10 19:00:02 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:02 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:02 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 4 Jan 10 19:00:02 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":434,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dreaming With You","artist":"Yao Si Ting ","album":"Angle","uri":"USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/05 - Dreaming With You.wav","trackType":"wav"} Jan 10 19:00:02 top-core-1 volumio[807]: verbose: CURRENT POSITION 4 Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 19:00:02 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 4 Jan 10 19:00:02 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dreaming With You","artist":"Yao Si Ting ","album":"Angle","uri":"USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/05 - Dreaming With You.wav","trackType":"wav"} Jan 10 19:00:02 top-core-1 volumio[807]: verbose: CURRENT POSITION 4 Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 19:00:02 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 4 Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 4 Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:02 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 4 Jan 10 19:00:02 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dreaming With You","artist":"Yao Si Ting ","album":"Angle","uri":"USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/05 - Dreaming With You.wav","trackType":"wav"} Jan 10 19:00:02 top-core-1 volumio[807]: verbose: CURRENT POSITION 4 Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 19:00:02 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 4 Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:02 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 4 Jan 10 19:00:02 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:02 top-core-1 volumio[807]: info: ------------------------------ 23ms Jan 10 19:00:02 top-core-1 volumio[807]: info: ------------------------------ 33ms Jan 10 19:00:02 top-core-1 volumio[807]: info: ------------------------------ 32ms Jan 10 19:00:05 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::ClearQueue Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::stop Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::stPlaybackTimer Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 4 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::serviceStop Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 4 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::serviceStop Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::stop Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::clearPlayQueue Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::addQueueItems Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::addQueueItems Jan 10 19:00:05 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 19:00:05 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/01 - Starry Starry Night.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/01 - Starry Starry Night.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/02 - Fields of Gold.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/02 - Fields of Gold.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/03 - Somewhere My Love.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/03 - Somewhere My Love.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/04 - Make Believe It's Your First Time.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/04 - Make Believe It's Your First Time.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/05 - Dreaming With You.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/05 - Dreaming With You.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/06 - Angle.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/06 - Angle.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/07 - A Living Prayer.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/07 - A Living Prayer.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/08 - Longer.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/08 - Longer.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPlay Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::play index 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::addQueueItems Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::addQueueItems Jan 10 19:00:05 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 19:00:05 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/09 - Unchained Melody.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/09 - Unchained Melody.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/10 - It's Doesn't Matter.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/10 - It's Doesn't Matter.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/11 - Last Christmas.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/11 - Last Christmas.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/12 - Just When I Needed You Most.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/12 - Just When I Needed You Most.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/13 - Speak Softly Love.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/13 - Speak Softly Love.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/14 - I Cry.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/14 - I Cry.wav Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::stop Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::play index undefined Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::startPlaybackTimer Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::clearAddPlayTracks USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/08 - Longer.wav Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 19:00:05 top-core-1 volumio[807]: info: Jan 10 19:00:05 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand stop took 26 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand stop took 3 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 19:00:05 top-core-1 volumio[807]: info: Jan 10 19:00:05 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:05 top-core-1 volumio[807]: info: Jan 10 19:00:05 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:05 top-core-1 volumio[807]: info: Jan 10 19:00:05 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:05 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand status took 4 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand clear took 4 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand status took 4 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand status took 3 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand add "USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/08 - Longer.wav" Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 10 19:00:05 top-core-1 volumio[807]: verbose: CURRENT POSITION 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService stop Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: No code Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 10 19:00:05 top-core-1 volumio[807]: verbose: CURRENT POSITION 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService stop Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: No code Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: ------------------------------ 35ms Jan 10 19:00:05 top-core-1 volumio[807]: info: ------------------------------ 35ms Jan 10 19:00:05 top-core-1 volumio[807]: info: Jan 10 19:00:05 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:05 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:05 top-core-1 volumio[807]: info: Jan 10 19:00:05 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:05 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:05 top-core-1 volumio[807]: error: updateQueue error: null Jan 10 19:00:05 top-core-1 volumio[807]: info: Jan 10 19:00:05 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:05 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:05 top-core-1 volumio[807]: info: ------------------------------ 37ms Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 35 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand add "USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/08 - Longer.wav" took 35 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: info: ------------------------------ 3ms Jan 10 19:00:05 top-core-1 volumio[807]: info: ------------------------------ 2ms Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand play Jan 10 19:00:05 top-core-1 volumio[807]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 10 19:00:05 top-core-1 volumio[807]: info: ------------------------------ 42ms Jan 10 19:00:05 top-core-1 volumio[807]: info: Jan 10 19:00:05 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:05 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:05 top-core-1 volumio[807]: info: Jan 10 19:00:05 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:05 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:05 top-core-1 volumio[807]: info: Jan 10 19:00:05 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:05 top-core-1 volumio[807]: info: ------------------------------ 8ms Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand play took 7 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: info: ------------------------------ 6ms Jan 10 19:00:05 top-core-1 volumio[807]: info: ------------------------------ 5ms Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:05 top-core-1 volumio[807]: info: Jan 10 19:00:05 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:05 top-core-1 volumio[807]: info: Jan 10 19:00:05 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:05 top-core-1 volumio[807]: info: Jan 10 19:00:05 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand status took 3 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand status took 2 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand status took 1 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:05 top-core-1 volumio[807]: info: Jan 10 19:00:05 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:05 top-core-1 volumio[807]: info: Jan 10 19:00:05 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand status took 4 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand status took 3 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand status took 2 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":242,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Longer","artist":"Yao Si Ting ","album":"Angle","uri":"USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/08 - Longer.wav","trackType":"wav"} Jan 10 19:00:05 top-core-1 volumio[807]: verbose: CURRENT POSITION 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":242,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Longer","artist":"Yao Si Ting ","album":"Angle","uri":"USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/08 - Longer.wav","trackType":"wav"} Jan 10 19:00:05 top-core-1 volumio[807]: verbose: CURRENT POSITION 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 19:00:05 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":242,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Longer","artist":"Yao Si Ting ","album":"Angle","uri":"USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/08 - Longer.wav","trackType":"wav"} Jan 10 19:00:05 top-core-1 volumio[807]: verbose: CURRENT POSITION 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 19:00:05 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: ------------------------------ 32ms Jan 10 19:00:05 top-core-1 volumio[807]: info: ------------------------------ 47ms Jan 10 19:00:05 top-core-1 volumio[807]: info: ------------------------------ 47ms Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 42 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 42 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 42 milliseconds Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:05 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":242,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Longer","artist":"Yao Si Ting ","album":"Angle","uri":"USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/08 - Longer.wav","trackType":"wav"} Jan 10 19:00:05 top-core-1 volumio[807]: verbose: CURRENT POSITION 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 19:00:05 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":242,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Longer","artist":"Yao Si Ting ","album":"Angle","uri":"USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/08 - Longer.wav","trackType":"wav"} Jan 10 19:00:05 top-core-1 volumio[807]: verbose: CURRENT POSITION 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 19:00:05 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":242,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Longer","artist":"Yao Si Ting ","album":"Angle","uri":"USB/Appreciate/4. Jazz & Blues/Yao Si Ting 『Angle』/08 - Longer.wav","trackType":"wav"} Jan 10 19:00:05 top-core-1 volumio[807]: verbose: CURRENT POSITION 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 19:00:05 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:05 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:05 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:05 top-core-1 volumio[807]: info: ------------------------------ 81ms Jan 10 19:00:05 top-core-1 volumio[807]: info: ------------------------------ 79ms Jan 10 19:00:05 top-core-1 volumio[807]: info: ------------------------------ 79ms Jan 10 19:00:09 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 19:00:09 top-core-1 volumio[807]: info: CURURI: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice Jan 10 19:00:09 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 19:00:09 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/01 - Emi fujita - Down By The Salley Gardens.wav Jan 10 19:00:09 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/02 - Noon - Sunny.wav Jan 10 19:00:09 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/05 - Dominica - Romeo And Julitte.wav Jan 10 19:00:09 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/06 - Dominica - Greensleeves.wav Jan 10 19:00:09 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/07 - Noon - Tears in Heaven.wav Jan 10 19:00:09 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav Jan 10 19:00:09 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/12 - Gwyneth Herbert - Grandma's Hands.wav Jan 10 19:00:09 top-core-1 volumio[807]: info: Preloading song: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/13 - Patricia Kaas - Where Do I Begin.wav Jan 10 19:00:09 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/01 - Emi fujita - Down By The Salley Gardens.wav in service mpd Jan 10 19:00:09 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Emi%20Fujita/Beautiful%20Female%20Voice%201/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FBeautiful%20Female%20Voice%2F01%20-%20Emi%20fujita%20-%20Down%20By%20The%20Salley%20Gardens.wav&metadata=false Jan 10 19:00:09 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/01 - Emi fujita - Down By The Salley Gardens.wav Jan 10 19:00:09 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/02 - Noon - Sunny.wav in service mpd Jan 10 19:00:09 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Noon/Beautiful%20Female%20Voice%201/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FBeautiful%20Female%20Voice%2F02%20-%20Noon%20-%20Sunny.wav&metadata=false Jan 10 19:00:09 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/02 - Noon - Sunny.wav Jan 10 19:00:09 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/05 - Dominica - Romeo And Julitte.wav in service mpd Jan 10 19:00:09 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Dominica/Beautiful%20Female%20Voice%201/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FBeautiful%20Female%20Voice%2F05%20-%20Dominica%20-%20Romeo%20And%20Julitte.wav&metadata=false Jan 10 19:00:09 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/05 - Dominica - Romeo And Julitte.wav Jan 10 19:00:09 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/06 - Dominica - Greensleeves.wav in service mpd Jan 10 19:00:09 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Dominica/Beautiful%20Female%20Voice%201/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FBeautiful%20Female%20Voice%2F06%20-%20Dominica%20-%20Greensleeves.wav&metadata=false Jan 10 19:00:09 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/06 - Dominica - Greensleeves.wav Jan 10 19:00:09 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/07 - Noon - Tears in Heaven.wav in service mpd Jan 10 19:00:09 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Noon/Beautiful%20Female%20Voice%201/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FBeautiful%20Female%20Voice%2F07%20-%20Noon%20-%20Tears%20in%20Heaven.wav&metadata=false Jan 10 19:00:09 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/07 - Noon - Tears in Heaven.wav Jan 10 19:00:10 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav in service mpd Jan 10 19:00:10 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Susan%20Osborn/Beautiful%20Female%20Voice%201/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FBeautiful%20Female%20Voice%2F10%20-%20Susan%20Osborn%20-%20Beauty%20And%20The%20Beast.wav&metadata=false Jan 10 19:00:10 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav Jan 10 19:00:10 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/12 - Gwyneth Herbert - Grandma's Hands.wav in service mpd Jan 10 19:00:10 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Gwyneth%20Herbert/Beautiful%20Female%20Voice%201/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FBeautiful%20Female%20Voice%2F12%20-%20Gwyneth%20Herbert%20-%20Grandma's%20Hands.wav&metadata=false Jan 10 19:00:10 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/12 - Gwyneth Herbert - Grandma's Hands.wav Jan 10 19:00:10 top-core-1 volumio[807]: info: Exploding uri music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/13 - Patricia Kaas - Where Do I Begin.wav in service mpd Jan 10 19:00:10 top-core-1 volumio[807]: info: ALBUMART /albumart?cacheid=70&web=Patricia%20Kaas/Beautiful%20Female%20Voice%201/extralarge&path=%2Fmnt%2FUSB%2FAppreciate%2F4.%20Jazz%20%26%20Blues%2FBeautiful%20Female%20Voice%2F13%20-%20Patricia%20Kaas%20-%20Where%20Do%20I%20Begin.wav&metadata=false Jan 10 19:00:10 top-core-1 volumio[807]: info: URI /mnt/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/13 - Patricia Kaas - Where Do I Begin.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::ClearQueue Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::stop Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::stPlaybackTimer Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::serviceStop Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 7 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::serviceStop Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::stop Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::clearPlayQueue Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::addQueueItems Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::addQueueItems Jan 10 19:00:11 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 19:00:11 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/01 - Emi fujita - Down By The Salley Gardens.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/01 - Emi fujita - Down By The Salley Gardens.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/02 - Noon - Sunny.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/02 - Noon - Sunny.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/05 - Dominica - Romeo And Julitte.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/05 - Dominica - Romeo And Julitte.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/06 - Dominica - Greensleeves.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/06 - Dominica - Greensleeves.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPlay Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::play index 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::addQueueItems Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::addQueueItems Jan 10 19:00:11 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 19:00:11 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/07 - Noon - Tears in Heaven.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/07 - Noon - Tears in Heaven.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/12 - Gwyneth Herbert - Grandma's Hands.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/12 - Gwyneth Herbert - Grandma's Hands.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/13 - Patricia Kaas - Where Do I Begin.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/13 - Patricia Kaas - Where Do I Begin.wav Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::stop Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::play index undefined Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::startPlaybackTimer Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::clearAddPlayTracks USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/06 - Dominica - Greensleeves.wav Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 19:00:11 top-core-1 volumio[807]: info: Jan 10 19:00:11 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand stop took 20 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand stop took 3 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 19:00:11 top-core-1 volumio[807]: info: Jan 10 19:00:11 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:11 top-core-1 volumio[807]: info: Jan 10 19:00:11 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:11 top-core-1 volumio[807]: info: Jan 10 19:00:11 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:11 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand status took 2 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand clear took 3 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand status took 2 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand status took 1 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand add "USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/06 - Dominica - Greensleeves.wav" Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 10 19:00:11 top-core-1 volumio[807]: verbose: CURRENT POSITION 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService stop Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: No code Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 10 19:00:11 top-core-1 volumio[807]: verbose: CURRENT POSITION 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService stop Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: No code Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: ------------------------------ 31ms Jan 10 19:00:11 top-core-1 volumio[807]: info: ------------------------------ 31ms Jan 10 19:00:11 top-core-1 volumio[807]: info: Jan 10 19:00:11 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:11 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:11 top-core-1 volumio[807]: info: Jan 10 19:00:11 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:11 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:11 top-core-1 volumio[807]: error: updateQueue error: null Jan 10 19:00:11 top-core-1 volumio[807]: info: Jan 10 19:00:11 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:11 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:11 top-core-1 volumio[807]: info: ------------------------------ 34ms Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 34 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand add "USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/06 - Dominica - Greensleeves.wav" took 34 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: info: ------------------------------ 4ms Jan 10 19:00:11 top-core-1 volumio[807]: info: ------------------------------ 3ms Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand play Jan 10 19:00:11 top-core-1 volumio[807]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 10 19:00:11 top-core-1 volumio[807]: info: ------------------------------ 39ms Jan 10 19:00:11 top-core-1 volumio[807]: info: Jan 10 19:00:11 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:11 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:11 top-core-1 volumio[807]: info: Jan 10 19:00:11 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:11 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:11 top-core-1 volumio[807]: info: Jan 10 19:00:11 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:11 top-core-1 volumio[807]: info: ------------------------------ 8ms Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand play took 7 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: info: ------------------------------ 6ms Jan 10 19:00:11 top-core-1 volumio[807]: info: ------------------------------ 5ms Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:11 top-core-1 volumio[807]: info: Jan 10 19:00:11 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:11 top-core-1 volumio[807]: info: Jan 10 19:00:11 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:11 top-core-1 volumio[807]: info: Jan 10 19:00:11 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand status took 4 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand status took 3 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand status took 2 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:11 top-core-1 volumio[807]: info: Jan 10 19:00:11 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:11 top-core-1 volumio[807]: info: Jan 10 19:00:11 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand status took 4 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand status took 2 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":247,"samplerate":"88.2 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Greensleeves","artist":"Dominica","album":"Beautiful Female Voice 1","uri":"USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/06 - Dominica - Greensleeves.wav","trackType":"wav"} Jan 10 19:00:11 top-core-1 volumio[807]: verbose: CURRENT POSITION 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":247,"samplerate":"88.2 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Greensleeves","artist":"Dominica","album":"Beautiful Female Voice 1","uri":"USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/06 - Dominica - Greensleeves.wav","trackType":"wav"} Jan 10 19:00:11 top-core-1 volumio[807]: verbose: CURRENT POSITION 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 19:00:11 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":247,"samplerate":"88.2 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Greensleeves","artist":"Dominica","album":"Beautiful Female Voice 1","uri":"USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/06 - Dominica - Greensleeves.wav","trackType":"wav"} Jan 10 19:00:11 top-core-1 volumio[807]: verbose: CURRENT POSITION 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 19:00:11 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: ------------------------------ 19ms Jan 10 19:00:11 top-core-1 volumio[807]: info: ------------------------------ 37ms Jan 10 19:00:11 top-core-1 volumio[807]: info: ------------------------------ 38ms Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 31 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 31 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 32 milliseconds Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:11 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":247,"samplerate":"88.2 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Greensleeves","artist":"Dominica","album":"Beautiful Female Voice 1","uri":"USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/06 - Dominica - Greensleeves.wav","trackType":"wav"} Jan 10 19:00:11 top-core-1 volumio[807]: verbose: CURRENT POSITION 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 19:00:11 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":247,"samplerate":"88.2 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Greensleeves","artist":"Dominica","album":"Beautiful Female Voice 1","uri":"USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/06 - Dominica - Greensleeves.wav","trackType":"wav"} Jan 10 19:00:11 top-core-1 volumio[807]: verbose: CURRENT POSITION 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 19:00:11 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":247,"samplerate":"88.2 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Greensleeves","artist":"Dominica","album":"Beautiful Female Voice 1","uri":"USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/06 - Dominica - Greensleeves.wav","trackType":"wav"} Jan 10 19:00:11 top-core-1 volumio[807]: verbose: CURRENT POSITION 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 19:00:11 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:11 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:11 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:11 top-core-1 volumio[807]: info: ------------------------------ 71ms Jan 10 19:00:11 top-core-1 volumio[807]: info: ------------------------------ 70ms Jan 10 19:00:11 top-core-1 volumio[807]: info: ------------------------------ 70ms Jan 10 19:00:19 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::ClearQueue Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::stop Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::stPlaybackTimer Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::serviceStop Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 3 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::serviceStop Jan 10 19:00:19 top-core-1 volumio[807]: info: ControllerMpd::stop Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::clearPlayQueue Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::addQueueItems Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::addQueueItems Jan 10 19:00:19 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 19:00:19 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/01 - Emi fujita - Down By The Salley Gardens.wav Jan 10 19:00:19 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/01 - Emi fujita - Down By The Salley Gardens.wav Jan 10 19:00:19 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/02 - Noon - Sunny.wav Jan 10 19:00:19 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/02 - Noon - Sunny.wav Jan 10 19:00:19 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/05 - Dominica - Romeo And Julitte.wav Jan 10 19:00:19 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/05 - Dominica - Romeo And Julitte.wav Jan 10 19:00:19 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/06 - Dominica - Greensleeves.wav Jan 10 19:00:19 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/06 - Dominica - Greensleeves.wav Jan 10 19:00:19 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/07 - Noon - Tears in Heaven.wav Jan 10 19:00:19 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/07 - Noon - Tears in Heaven.wav Jan 10 19:00:19 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav Jan 10 19:00:19 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPlay Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::play index 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::addQueueItems Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::addQueueItems Jan 10 19:00:19 top-core-1 volumio[807]: info: Preload queue cleared Jan 10 19:00:19 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/12 - Gwyneth Herbert - Grandma's Hands.wav Jan 10 19:00:19 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/12 - Gwyneth Herbert - Grandma's Hands.wav Jan 10 19:00:19 top-core-1 volumio[807]: info: Adding Item to queue: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/13 - Patricia Kaas - Where Do I Begin.wav Jan 10 19:00:19 top-core-1 volumio[807]: info: Using cached record of: music-library/USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/13 - Patricia Kaas - Where Do I Begin.wav Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::stop Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushQueue Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::saveQueue Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::play index undefined Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::updateTrackBlock Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrackBlock Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::startPlaybackTimer Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::clearAddPlayTracks USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 19:00:19 top-core-1 volumio[807]: info: Jan 10 19:00:19 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand stop took 17 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand stop took 3 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 19:00:19 top-core-1 volumio[807]: info: Jan 10 19:00:19 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:19 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:19 top-core-1 volumio[807]: info: Jan 10 19:00:19 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:19 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:19 top-core-1 volumio[807]: info: Jan 10 19:00:19 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:19 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand status took 7 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand clear took 7 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand add "USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav" Jan 10 19:00:19 top-core-1 volumio[807]: info: Jan 10 19:00:19 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:19 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:19 top-core-1 volumio[807]: error: updateQueue error: null Jan 10 19:00:19 top-core-1 volumio[807]: info: Jan 10 19:00:19 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:19 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand status took 7 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand status took 6 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: info: ------------------------------ 6ms Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand add "USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav" took 5 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand play Jan 10 19:00:19 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 10 19:00:19 top-core-1 volumio[807]: verbose: CURRENT POSITION 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService stop Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:19 top-core-1 volumio[807]: info: No code Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:19 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 10 19:00:19 top-core-1 volumio[807]: verbose: CURRENT POSITION 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService stop Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:19 top-core-1 volumio[807]: info: No code Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:19 top-core-1 volumio[807]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 10 19:00:19 top-core-1 volumio[807]: info: ------------------------------ 46ms Jan 10 19:00:19 top-core-1 volumio[807]: info: ------------------------------ 42ms Jan 10 19:00:19 top-core-1 volumio[807]: info: ------------------------------ 49ms Jan 10 19:00:19 top-core-1 volumio[807]: info: Jan 10 19:00:19 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:19 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:19 top-core-1 volumio[807]: info: Jan 10 19:00:19 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:19 top-core-1 volumio[807]: info: Jan 10 19:00:19 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:19 top-core-1 volumio[807]: info: ------------------------------ 43ms Jan 10 19:00:19 top-core-1 volumio[807]: info: ------------------------------ 39ms Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand play took 37 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:19 top-core-1 volumio[807]: info: ------------------------------ 2ms Jan 10 19:00:19 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:19 top-core-1 volumio[807]: info: Jan 10 19:00:19 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:19 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:19 top-core-1 volumio[807]: info: Jan 10 19:00:19 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:19 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:19 top-core-1 volumio[807]: info: Jan 10 19:00:19 top-core-1 volumio[807]: ---------------------------- MPD announces system playlist update Jan 10 19:00:19 top-core-1 volumio[807]: info: Ignoring MPD Status Update Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand status took 6 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand status took 6 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: info: ------------------------------ 5ms Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand status took 5 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: info: ------------------------------ 4ms Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:19 top-core-1 volumio[807]: info: Jan 10 19:00:19 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:19 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 21 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 22 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 22 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand status took 20 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:19 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"88.2 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beauty And The Beast","artist":"Susan Osborn","album":"Beautiful Female Voice 1","uri":"USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav","trackType":"wav"} Jan 10 19:00:19 top-core-1 volumio[807]: verbose: CURRENT POSITION 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus stop Jan 10 19:00:19 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"88.2 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beauty And The Beast","artist":"Susan Osborn","album":"Beautiful Female Voice 1","uri":"USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav","trackType":"wav"} Jan 10 19:00:19 top-core-1 volumio[807]: verbose: CURRENT POSITION 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 19:00:19 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:19 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"88.2 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beauty And The Beast","artist":"Susan Osborn","album":"Beautiful Female Voice 1","uri":"USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav","trackType":"wav"} Jan 10 19:00:19 top-core-1 volumio[807]: verbose: CURRENT POSITION 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 19:00:19 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:19 top-core-1 volumio[807]: info: ------------------------------ 43ms Jan 10 19:00:19 top-core-1 volumio[807]: info: ------------------------------ 58ms Jan 10 19:00:19 top-core-1 volumio[807]: info: ------------------------------ 57ms Jan 10 19:00:19 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 31 milliseconds Jan 10 19:00:19 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:19 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"88.2 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beauty And The Beast","artist":"Susan Osborn","album":"Beautiful Female Voice 1","uri":"USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav","trackType":"wav"} Jan 10 19:00:19 top-core-1 volumio[807]: verbose: CURRENT POSITION 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService play Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus play Jan 10 19:00:19 top-core-1 volumio[807]: info: Received an update from plugin. extracting info from payload Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:19 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:19 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:19 top-core-1 volumio[807]: info: ------------------------------ 66ms Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPause Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreStateMachine::pause Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreStateMachine::stPlaybackTimer Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreStateMachine::servicePause Jan 10 19:00:25 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreCommandRouter::servicePause Jan 10 19:00:25 top-core-1 volumio[807]: info: ControllerMpd::pause Jan 10 19:00:25 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand pause Jan 10 19:00:25 top-core-1 volumio[807]: info: Jan 10 19:00:25 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:25 top-core-1 volumio[807]: info: sendMpdCommand pause took 1 milliseconds Jan 10 19:00:25 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:25 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:25 top-core-1 volumio[807]: info: Jan 10 19:00:25 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:25 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:25 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:25 top-core-1 volumio[807]: info: Jan 10 19:00:25 top-core-1 volumio[807]: ---------------------------- MPD announces state update: player Jan 10 19:00:25 top-core-1 volumio[807]: info: ControllerMpd::getState Jan 10 19:00:25 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand status Jan 10 19:00:25 top-core-1 volumio[807]: info: sendMpdCommand status took 1 milliseconds Jan 10 19:00:25 top-core-1 volumio[807]: info: sendMpdCommand status took 2 milliseconds Jan 10 19:00:25 top-core-1 volumio[807]: info: sendMpdCommand status took 1 milliseconds Jan 10 19:00:25 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:25 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:25 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:25 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:25 top-core-1 volumio[807]: verbose: ControllerMpd::parseState Jan 10 19:00:25 top-core-1 volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 19:00:25 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 10 19:00:25 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 10 19:00:25 top-core-1 volumio[807]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 10 19:00:25 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:25 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:25 top-core-1 volumio[807]: verbose: ControllerMpd::parseTrackInfo Jan 10 19:00:25 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:25 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:25 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":6372,"duration":213,"samplerate":"88.2 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beauty And The Beast","artist":"Susan Osborn","album":"Beautiful Female Voice 1","uri":"USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav","trackType":"wav"} Jan 10 19:00:25 top-core-1 volumio[807]: verbose: CURRENT POSITION 5 Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService pause Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus pause Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:25 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreStateMachine::stPlaybackTimer Jan 10 19:00:25 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:25 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:25 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":6372,"duration":213,"samplerate":"88.2 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beauty And The Beast","artist":"Susan Osborn","album":"Beautiful Female Voice 1","uri":"USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav","trackType":"wav"} Jan 10 19:00:25 top-core-1 volumio[807]: verbose: CURRENT POSITION 5 Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService pause Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus pause Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:25 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreStateMachine::stPlaybackTimer Jan 10 19:00:25 top-core-1 volumio[807]: info: ControllerMpd::pushState Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreCommandRouter::servicePushState Jan 10 19:00:25 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:25 top-core-1 volumio[807]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":6372,"duration":213,"samplerate":"88.2 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beauty And The Beast","artist":"Susan Osborn","album":"Beautiful Female Voice 1","uri":"USB/Appreciate/4. Jazz & Blues/Beautiful Female Voice/10 - Susan Osborn - Beauty And The Beast.wav","trackType":"wav"} Jan 10 19:00:25 top-core-1 volumio[807]: verbose: CURRENT POSITION 5 Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreStateMachine::syncState stateService pause Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreStateMachine::syncState currentStatus pause Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreStateMachine::pushState Jan 10 19:00:25 top-core-1 volumio[807]: info: CorePlayQueue::getTrack 5 Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreCommandRouter::volumioPushState Jan 10 19:00:25 top-core-1 volumio[807]: info: CoreStateMachine::stPlaybackTimer Jan 10 19:00:25 top-core-1 volumio[807]: info: ------------------------------ 26ms Jan 10 19:00:25 top-core-1 volumio[807]: info: ------------------------------ 26ms Jan 10 19:00:25 top-core-1 volumio[807]: info: ------------------------------ 25ms Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 19:00:26 top-core-1 volumio[807]: info: Retrieving Cloud Streaming UI Jan 10 19:00:26 top-core-1 volumio[807]: info: Getting Tidal Cloud Configuration Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 19:00:26 top-core-1 volumio[807]: info: Getting Qobuz Cloud Configuration Jan 10 19:00:26 top-core-1 volumio[807]: info: Asking plugin for UI Config Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 19:00:26 top-core-1 volumio[807]: info: Getting Spotify Cloud Configuration Jan 10 19:00:26 top-core-1 volumio[807]: info: Asking plugin for UI Config Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 19:00:26 top-core-1 volumio[807]: info: Saving Spotify Acccount Jan 10 19:00:26 top-core-1 volumio[807]: info: Got Tidal Cloud Configuration Jan 10 19:00:26 top-core-1 volumio[807]: info: Got it Jan 10 19:00:26 top-core-1 volumio[807]: info: Got it Jan 10 19:00:26 top-core-1 volumio[807]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 19:00:26 top-core-1 volumio[807]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::volumioGetBrowseSources Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::volumioGetBrowseSources Jan 10 19:00:26 top-core-1 volumio[807]: info: CoreCommandRouter::volumioGetBrowseSources Jan 10 19:00:27 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 10 19:00:27 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 10 19:00:29 top-core-1 volumio[807]: info: Disabling MyMusic plugin upnp Jan 10 19:00:29 top-core-1 sudo[11407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jan 10 19:00:29 top-core-1 sudo[11407]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 19:00:29 top-core-1 sudo[11407]: pam_unix(sudo:session): session closed for user root Jan 10 19:00:29 top-core-1 volumio[807]: info: Disabling plugin upnp Jan 10 19:00:29 top-core-1 volumio[807]: info: Done. Jan 10 19:00:30 top-core-1 volumio[807]: info: Enabling MyMusic plugin upnp Jan 10 19:00:30 top-core-1 volumio[807]: info: Enabling plugin upnp Jan 10 19:00:30 top-core-1 volumio[807]: info: Loading plugin "upnp"... Jan 10 19:00:30 top-core-1 volumio[807]: info: [1768039230188] Starting Upmpd Daemon Jan 10 19:00:30 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 10 19:00:30 top-core-1 volumio[807]: info: Done. Jan 10 19:00:31 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 10 19:00:36 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 10 19:00:40 top-core-1 sudo[11424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 10 19:00:40 top-core-1 sudo[11424]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 19:00:40 top-core-1 sudo[11424]: pam_unix(sudo:session): session closed for user root Jan 10 19:00:40 top-core-1 sudo[11426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 19:00:40 top-core-1 sudo[11426]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 19:00:40 top-core-1 sudo[11426]: pam_unix(sudo:session): session closed for user root Jan 10 19:00:40 top-core-1 sudo[11430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 10 19:00:40 top-core-1 sudo[11430]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 19:00:40 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 10 19:00:40 top-core-1 systemd[1]: Started UPnP Renderer front-end to MPD. Jan 10 19:00:40 top-core-1 sudo[11430]: pam_unix(sudo:session): session closed for user root Jan 10 19:00:40 top-core-1 volumio[807]: info: Upmpdcli Daemon Started Jan 10 19:00:40 top-core-1 volumio[11432]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 10 19:00:42 top-core-1 volumiologrotate[623]: ls: cannot access '/var/log/samba/log.wb-TOP': No such file or directory Jan 10 19:00:42 top-core-1 volumiologrotate[623]: ls: cannot access 'CORE': No such file or directory Jan 10 19:00:42 top-core-1 volumiologrotate[623]: ls: cannot access '1': No such file or directory Jan 10 19:00:44 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 10 19:00:48 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 10 19:00:52 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 10 19:00:53 top-core-1 volumio[11432]: ..................................................................................................................................................++++ Jan 10 19:00:55 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 10 19:00:56 top-core-1 volumio[807]: info: Disabling MyMusic plugin upnp Jan 10 19:00:56 top-core-1 sudo[11524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jan 10 19:00:56 top-core-1 sudo[11524]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 19:00:56 top-core-1 systemd[1]: Stopping UPnP Renderer front-end to MPD... Jan 10 19:00:58 top-core-1 volumio[807]: info: Enabling MyMusic plugin upnp Jan 10 19:00:58 top-core-1 volumio[807]: info: Enabling plugin upnp Jan 10 19:00:58 top-core-1 volumio[807]: info: Loading plugin "upnp"... Jan 10 19:00:58 top-core-1 volumio[807]: info: [1768039258125] Starting Upmpd Daemon Jan 10 19:00:58 top-core-1 volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 10 19:00:58 top-core-1 volumio[807]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 19:00:58 top-core-1 volumio[807]: Error: listen EADDRINUSE: address already in use :::6599 Jan 10 19:00:58 top-core-1 volumio[807]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Jan 10 19:00:58 top-core-1 volumio[807]: at listenInCluster (net.js:1366:12) Jan 10 19:00:58 top-core-1 volumio[807]: at Server.listen (net.js:1452:7) Jan 10 19:00:58 top-core-1 volumio[807]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jan 10 19:00:58 top-core-1 volumio[807]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jan 10 19:00:58 top-core-1 volumio[807]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jan 10 19:00:58 top-core-1 volumio[807]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 10 19:00:58 top-core-1 volumio[807]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jan 10 19:00:58 top-core-1 volumio[807]: code: 'EADDRINUSE', Jan 10 19:00:58 top-core-1 volumio[807]: errno: -98, Jan 10 19:00:58 top-core-1 volumio[807]: syscall: 'listen', Jan 10 19:00:58 top-core-1 volumio[807]: address: '::', Jan 10 19:00:58 top-core-1 volumio[807]: port: 6599 Jan 10 19:00:58 top-core-1 volumio[807]: } Jan 10 19:00:58 top-core-1 volumio[807]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 19:00:58 top-core-1 sudo[11552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-10 18:59 Jan 10 19:00:58 top-core-1 sudo[11552]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"