-- Logs begin at Mon 2024-12-23 20:13:08 MSK, end at Wed 2025-02-19 18:52:19 MSK. -- Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPlay Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index 2 Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreStateMachine::stop Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index undefined Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 2 Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreStateMachine::startPlaybackTimer Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 2 Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: ControllerMpd::clearAddPlayTracks USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/003. Mr. Big - Wild World.flac Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 2 milliseconds Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand clear Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: Feb 19 18:51:00 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: sendMpdCommand clear took 1 milliseconds Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/003. Mr. Big - Wild World.flac" Feb 19 18:51:00 zykow-volumio4 volumio[946]: error: updateQueue error: null Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: Feb 19 18:51:00 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: ------------------------------ 3ms Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/003. Mr. Big - Wild World.flac" took 2 milliseconds Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand play Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: ------------------------------ 2ms Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: sendMpdCommand play took 1 milliseconds Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: Feb 19 18:51:00 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:00 zykow-volumio4 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: Feb 19 18:51:00 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 18 milliseconds Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 2 milliseconds Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 2 Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wild World","artist":"Mr. Big","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/003. Mr. Big - Wild World.flac","trackType":"flac"} Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 2 Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: ------------------------------ 23ms Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 2 Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wild World","artist":"Mr. Big","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/003. Mr. Big - Wild World.flac","trackType":"flac"} Feb 19 18:51:00 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 2 Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus play Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: Received an update from plugin. extracting info from payload Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 2 Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 2 Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:00 zykow-volumio4 volumio[946]: info: ------------------------------ 14ms Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPlay Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index 2 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::stop Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::stPlaybackTimer Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::updateTrackBlock Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrackBlock Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 2 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::serviceStop Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 2 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::serviceStop Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ControllerMpd::stop Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: Feb 19 18:51:02 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 50 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index undefined Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 2 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::startPlaybackTimer Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 2 Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::clearAddPlayTracks USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/003. Mr. Big - Wild World.flac Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 3 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 1 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand clear Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: Feb 19 18:51:02 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand clear took 2 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/003. Mr. Big - Wild World.flac" Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 2 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 2 Feb 19 18:51:02 zykow-volumio4 volumio[946]: 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":"Wild World","artist":"Mr. Big","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/003. Mr. Big - Wild World.flac","trackType":"flac"} Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 2 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService stop Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 2 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: No code Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 2 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ------------------------------ 23ms Feb 19 18:51:02 zykow-volumio4 volumio[946]: error: updateQueue error: null Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: Feb 19 18:51:02 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ------------------------------ 18ms Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/003. Mr. Big - Wild World.flac" took 15 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand play Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: Feb 19 18:51:02 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ------------------------------ 6ms Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand play took 5 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPlay Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index 1 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:02 zykow-volumio4 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::stop Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index undefined Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 1 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::startPlaybackTimer Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 1 Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::clearAddPlayTracks USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/002. Pretenders - I'll Stand By You.flac Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: Feb 19 18:51:02 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 12 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: Feb 19 18:51:02 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 4 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand clear Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: Feb 19 18:51:02 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 5 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 3 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand clear took 2 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/002. Pretenders - I'll Stand By You.flac" Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 1 Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wild World","artist":"Mr. Big","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/003. Mr. Big - Wild World.flac","trackType":"flac"} Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 1 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ------------------------------ 27ms Feb 19 18:51:02 zykow-volumio4 volumio[946]: error: updateQueue error: null Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: Feb 19 18:51:02 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ------------------------------ 10ms Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/002. Pretenders - I'll Stand By You.flac" took 6 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand play Feb 19 18:51:02 zykow-volumio4 volumio[946]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 19 18:51:02 zykow-volumio4 volumio[946]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ------------------------------ 21ms Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ------------------------------ 17ms Feb 19 18:51:02 zykow-volumio4 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: Feb 19 18:51:02 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ------------------------------ 87ms Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand play took 83 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: Feb 19 18:51:02 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 17 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 3 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 1 Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"674 Kbps","isStreaming":false,"title":"I'll Stand By You","artist":"Pretenders","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/002. Pretenders - I'll Stand By You.flac","trackType":"flac"} Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 1 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus play Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: Received an update from plugin. extracting info from payload Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 1 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 1 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ------------------------------ 34ms Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 1 Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"674 Kbps","isStreaming":false,"title":"I'll Stand By You","artist":"Pretenders","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/002. Pretenders - I'll Stand By You.flac","trackType":"flac"} Feb 19 18:51:02 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 1 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus play Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: Received an update from plugin. extracting info from payload Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 1 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 1 Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:02 zykow-volumio4 volumio[946]: info: ------------------------------ 28ms Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPlay Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index 14 Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::stop Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::stPlaybackTimer Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::updateTrackBlock Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrackBlock Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 1 Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::serviceStop Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 1 Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreCommandRouter::serviceStop Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: ControllerMpd::stop Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: Feb 19 18:51:20 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 29 milliseconds Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index undefined Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 14 Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::startPlaybackTimer Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 14 Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::clearAddPlayTracks USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/015. U.F.O. - Bella Donna.flac Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 2 milliseconds Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 1 milliseconds Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand clear Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: Feb 19 18:51:20 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: sendMpdCommand clear took 2 milliseconds Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/015. U.F.O. - Bella Donna.flac" Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 14 Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 14 Feb 19 18:51:20 zykow-volumio4 volumio[946]: 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":"I'll Stand By You","artist":"Pretenders","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/002. Pretenders - I'll Stand By You.flac","trackType":"flac"} Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 14 Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService stop Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 14 Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: No code Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 14 Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: ------------------------------ 18ms Feb 19 18:51:20 zykow-volumio4 volumio[946]: error: updateQueue error: null Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: Feb 19 18:51:20 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: ------------------------------ 14ms Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/015. U.F.O. - Bella Donna.flac" took 12 milliseconds Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand play Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: ------------------------------ 5ms Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: sendMpdCommand play took 4 milliseconds Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: Feb 19 18:51:20 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:20 zykow-volumio4 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: Feb 19 18:51:20 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 14 milliseconds Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 2 milliseconds Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 14 Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":266,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bella Donna","artist":"U.F.O.","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/015. U.F.O. - Bella Donna.flac","trackType":"flac"} Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 14 Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: ------------------------------ 20ms Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 14 Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":266,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bella Donna","artist":"U.F.O.","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/015. U.F.O. - Bella Donna.flac","trackType":"flac"} Feb 19 18:51:20 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 14 Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus play Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: Received an update from plugin. extracting info from payload Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 14 Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 14 Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:20 zykow-volumio4 volumio[946]: info: ------------------------------ 16ms Feb 19 18:51:22 zykow-volumio4 volumio[946]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Feb 19 18:51:22 zykow-volumio4 volumio[946]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Feb 19 18:51:22 zykow-volumio4 volumio[946]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Feb 19 18:51:22 zykow-volumio4 volumio[946]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Feb 19 18:51:22 zykow-volumio4 volumio[946]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Feb 19 18:51:22 zykow-volumio4 volumio[946]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:440:9) Feb 19 18:51:22 zykow-volumio4 volumio[946]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 19 18:51:22 zykow-volumio4 volumio[946]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 19 18:51:23 zykow-volumio4 volumio[946]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Feb 19 18:51:23 zykow-volumio4 volumio[946]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Feb 19 18:51:23 zykow-volumio4 volumio[946]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Feb 19 18:51:23 zykow-volumio4 volumio[946]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Feb 19 18:51:23 zykow-volumio4 volumio[946]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Feb 19 18:51:23 zykow-volumio4 volumio[946]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:440:9) Feb 19 18:51:23 zykow-volumio4 volumio[946]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 19 18:51:23 zykow-volumio4 volumio[946]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 19 18:51:23 zykow-volumio4 volumio[946]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Feb 19 18:51:23 zykow-volumio4 volumio[946]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Feb 19 18:51:23 zykow-volumio4 volumio[946]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Feb 19 18:51:23 zykow-volumio4 volumio[946]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Feb 19 18:51:23 zykow-volumio4 volumio[946]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Feb 19 18:51:23 zykow-volumio4 volumio[946]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:440:9) Feb 19 18:51:23 zykow-volumio4 volumio[946]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 19 18:51:23 zykow-volumio4 volumio[946]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPlay Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index 16 Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::stop Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::stPlaybackTimer Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::updateTrackBlock Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrackBlock Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 14 Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::serviceStop Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 14 Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreCommandRouter::serviceStop Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: ControllerMpd::stop Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: Feb 19 18:51:24 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 15 milliseconds Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index undefined Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 16 Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::startPlaybackTimer Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 16 Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::clearAddPlayTracks USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/017. The Byrds - Ballad Of Easy Rider.flac Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 4 milliseconds Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 3 milliseconds Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand clear Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: Feb 19 18:51:24 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: sendMpdCommand clear took 3 milliseconds Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/017. The Byrds - Ballad Of Easy Rider.flac" Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 16 Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 16 Feb 19 18:51:24 zykow-volumio4 volumio[946]: 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":"Bella Donna","artist":"U.F.O.","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/015. U.F.O. - Bella Donna.flac","trackType":"flac"} Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 16 Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService stop Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 16 Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: No code Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 16 Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: ------------------------------ 19ms Feb 19 18:51:24 zykow-volumio4 volumio[946]: error: updateQueue error: null Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: Feb 19 18:51:24 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: ------------------------------ 13ms Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/017. The Byrds - Ballad Of Easy Rider.flac" took 13 milliseconds Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand play Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: ------------------------------ 5ms Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: sendMpdCommand play took 4 milliseconds Feb 19 18:51:24 zykow-volumio4 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: Feb 19 18:51:24 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: Feb 19 18:51:24 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 2 milliseconds Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 2 milliseconds Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 16 Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":123,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"782 Kbps","isStreaming":false,"title":"Ballad Of Easy Rider","artist":"The Byrds","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/017. The Byrds - Ballad Of Easy Rider.flac","trackType":"flac"} Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 16 Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: ------------------------------ 13ms Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 16 Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":123,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"782 Kbps","isStreaming":false,"title":"Ballad Of Easy Rider","artist":"The Byrds","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/017. The Byrds - Ballad Of Easy Rider.flac","trackType":"flac"} Feb 19 18:51:24 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 16 Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus play Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: Received an update from plugin. extracting info from payload Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 16 Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 16 Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:24 zykow-volumio4 volumio[946]: info: ------------------------------ 28ms Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPlay Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index 18 Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::stop Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::stPlaybackTimer Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::updateTrackBlock Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrackBlock Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 16 Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::serviceStop Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 16 Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreCommandRouter::serviceStop Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: ControllerMpd::stop Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: Feb 19 18:51:27 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 24 milliseconds Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index undefined Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 18 Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::startPlaybackTimer Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 18 Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::clearAddPlayTracks USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/019. Motorhead - I Ain't No Nice Guy.flac Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 3 milliseconds Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 1 milliseconds Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand clear Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: Feb 19 18:51:27 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: sendMpdCommand clear took 2 milliseconds Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/019. Motorhead - I Ain't No Nice Guy.flac" Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 18 Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 18 Feb 19 18:51:27 zykow-volumio4 volumio[946]: 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":"Ballad Of Easy Rider","artist":"The Byrds","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/017. The Byrds - Ballad Of Easy Rider.flac","trackType":"flac"} Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 18 Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService stop Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 18 Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: No code Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 18 Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: ------------------------------ 21ms Feb 19 18:51:27 zykow-volumio4 volumio[946]: error: updateQueue error: null Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: Feb 19 18:51:27 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: ------------------------------ 16ms Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/019. Motorhead - I Ain't No Nice Guy.flac" took 14 milliseconds Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand play Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: ------------------------------ 5ms Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: sendMpdCommand play took 3 milliseconds Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: Feb 19 18:51:27 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:27 zykow-volumio4 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: Feb 19 18:51:27 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 14 milliseconds Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 1 milliseconds Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 18 Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Ain't No Nice Guy","artist":"Motorhead","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/019. Motorhead - I Ain't No Nice Guy.flac","trackType":"flac"} Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 18 Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 18 Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Ain't No Nice Guy","artist":"Motorhead","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/019. Motorhead - I Ain't No Nice Guy.flac","trackType":"flac"} Feb 19 18:51:27 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 18 Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus play Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: Received an update from plugin. extracting info from payload Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 18 Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 18 Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: ------------------------------ 27ms Feb 19 18:51:27 zykow-volumio4 volumio[946]: info: ------------------------------ 16ms Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPlay Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index 22 Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::stop Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::stPlaybackTimer Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::updateTrackBlock Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrackBlock Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 18 Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::serviceStop Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 18 Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreCommandRouter::serviceStop Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: ControllerMpd::stop Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: Feb 19 18:51:30 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 12 milliseconds Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index undefined Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 22 Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::startPlaybackTimer Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 22 Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::clearAddPlayTracks USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/023. Maggie Reilly - To France.flac Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 1 milliseconds Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 0 milliseconds Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand clear Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: Feb 19 18:51:30 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: sendMpdCommand clear took 1 milliseconds Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/023. Maggie Reilly - To France.flac" Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 22 Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 22 Feb 19 18:51:30 zykow-volumio4 volumio[946]: 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":"I Ain't No Nice Guy","artist":"Motorhead","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/019. Motorhead - I Ain't No Nice Guy.flac","trackType":"flac"} Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 22 Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService stop Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 22 Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: No code Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 22 Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: ------------------------------ 14ms Feb 19 18:51:30 zykow-volumio4 volumio[946]: error: updateQueue error: null Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: Feb 19 18:51:30 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: ------------------------------ 10ms Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/023. Maggie Reilly - To France.flac" took 9 milliseconds Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand play Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: ------------------------------ 6ms Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: sendMpdCommand play took 5 milliseconds Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: Feb 19 18:51:30 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:30 zykow-volumio4 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: Feb 19 18:51:30 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 14 milliseconds Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 2 milliseconds Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 22 Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":309,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"To France","artist":"Maggie Reilly","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/023. Maggie Reilly - To France.flac","trackType":"flac"} Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 22 Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: ------------------------------ 19ms Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 22 Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":309,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"To France","artist":"Maggie Reilly","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/023. Maggie Reilly - To France.flac","trackType":"flac"} Feb 19 18:51:30 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 22 Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus play Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: Received an update from plugin. extracting info from payload Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 22 Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 22 Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:30 zykow-volumio4 volumio[946]: info: ------------------------------ 16ms Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPlay Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index 24 Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::stop Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::stPlaybackTimer Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::updateTrackBlock Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrackBlock Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 22 Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::serviceStop Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 22 Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreCommandRouter::serviceStop Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: ControllerMpd::stop Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: Feb 19 18:51:34 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 7 milliseconds Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index undefined Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 24 Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::startPlaybackTimer Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 24 Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::clearAddPlayTracks USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/025. Poison - Every Rose Has Its Thorn.flac Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 2 milliseconds Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 1 milliseconds Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand clear Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: Feb 19 18:51:34 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: sendMpdCommand clear took 2 milliseconds Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/025. Poison - Every Rose Has Its Thorn.flac" Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 24 Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 24 Feb 19 18:51:34 zykow-volumio4 volumio[946]: 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":"To France","artist":"Maggie Reilly","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/023. Maggie Reilly - To France.flac","trackType":"flac"} Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 24 Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService stop Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 24 Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: No code Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 24 Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: ------------------------------ 14ms Feb 19 18:51:34 zykow-volumio4 volumio[946]: error: updateQueue error: null Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: Feb 19 18:51:34 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: ------------------------------ 11ms Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/025. Poison - Every Rose Has Its Thorn.flac" took 10 milliseconds Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand play Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: ------------------------------ 11ms Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: sendMpdCommand play took 11 milliseconds Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: Feb 19 18:51:34 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:34 zykow-volumio4 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 15 milliseconds Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: Feb 19 18:51:34 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 24 Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Every Rose Has Its Thorn","artist":"Poison","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/025. Poison - Every Rose Has Its Thorn.flac","trackType":"flac"} Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 24 Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: ------------------------------ 19ms Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 2 milliseconds Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 24 Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Every Rose Has Its Thorn","artist":"Poison","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/025. Poison - Every Rose Has Its Thorn.flac","trackType":"flac"} Feb 19 18:51:34 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 24 Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus play Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: Received an update from plugin. extracting info from payload Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 24 Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 24 Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:34 zykow-volumio4 volumio[946]: info: ------------------------------ 12ms Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPlay Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index 25 Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::stop Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::stPlaybackTimer Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::updateTrackBlock Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrackBlock Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 24 Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::serviceStop Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 24 Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreCommandRouter::serviceStop Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: ControllerMpd::stop Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: Feb 19 18:51:37 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 103 milliseconds Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index undefined Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 25 Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::startPlaybackTimer Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 25 Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::clearAddPlayTracks USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/026. Kansas - Dust In The Wind.flac Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 2 milliseconds Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 1 milliseconds Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand clear Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: Feb 19 18:51:37 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: sendMpdCommand clear took 2 milliseconds Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/026. Kansas - Dust In The Wind.flac" Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 25 Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 25 Feb 19 18:51:37 zykow-volumio4 volumio[946]: 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":"Every Rose Has Its Thorn","artist":"Poison","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/025. Poison - Every Rose Has Its Thorn.flac","trackType":"flac"} Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 25 Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService stop Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 25 Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: No code Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 25 Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: ------------------------------ 12ms Feb 19 18:51:37 zykow-volumio4 volumio[946]: error: updateQueue error: null Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: Feb 19 18:51:37 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: ------------------------------ 10ms Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/026. Kansas - Dust In The Wind.flac" took 9 milliseconds Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand play Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: ------------------------------ 4ms Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: sendMpdCommand play took 3 milliseconds Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: Feb 19 18:51:37 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:37 zykow-volumio4 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: Feb 19 18:51:37 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 15 milliseconds Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 1 milliseconds Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 25 Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dust In The Wind","artist":"Kansas","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/026. Kansas - Dust In The Wind.flac","trackType":"flac"} Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 25 Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: ------------------------------ 20ms Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 25 Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dust In The Wind","artist":"Kansas","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/026. Kansas - Dust In The Wind.flac","trackType":"flac"} Feb 19 18:51:37 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 25 Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus play Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: Received an update from plugin. extracting info from payload Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 25 Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 25 Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:37 zykow-volumio4 volumio[946]: info: ------------------------------ 14ms Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPlay Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index 26 Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::stop Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::stPlaybackTimer Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::updateTrackBlock Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrackBlock Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 25 Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::serviceStop Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 25 Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreCommandRouter::serviceStop Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: ControllerMpd::stop Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: Feb 19 18:51:42 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 18 milliseconds Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index undefined Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 26 Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::startPlaybackTimer Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 26 Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::clearAddPlayTracks USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/027. Bonfire - Rivers Of Glory.flac Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 2 milliseconds Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 0 milliseconds Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand clear Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: Feb 19 18:51:42 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: sendMpdCommand clear took 1 milliseconds Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/027. Bonfire - Rivers Of Glory.flac" Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 26 Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 26 Feb 19 18:51:42 zykow-volumio4 volumio[946]: 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":"Dust In The Wind","artist":"Kansas","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/026. Kansas - Dust In The Wind.flac","trackType":"flac"} Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 26 Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService stop Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 26 Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: No code Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 26 Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: ------------------------------ 15ms Feb 19 18:51:42 zykow-volumio4 volumio[946]: error: updateQueue error: null Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: Feb 19 18:51:42 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: ------------------------------ 14ms Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/027. Bonfire - Rivers Of Glory.flac" took 13 milliseconds Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand play Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: ------------------------------ 8ms Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: sendMpdCommand play took 5 milliseconds Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: Feb 19 18:51:42 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:42 zykow-volumio4 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: Feb 19 18:51:42 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 15 milliseconds Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 2 milliseconds Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 26 Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rivers Of Glory","artist":"Bonfire","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/027. Bonfire - Rivers Of Glory.flac","trackType":"flac"} Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 26 Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: ------------------------------ 20ms Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 26 Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rivers Of Glory","artist":"Bonfire","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/027. Bonfire - Rivers Of Glory.flac","trackType":"flac"} Feb 19 18:51:42 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 26 Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus play Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: Received an update from plugin. extracting info from payload Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 26 Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 26 Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:42 zykow-volumio4 volumio[946]: info: ------------------------------ 13ms Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPlay Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index 29 Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::stop Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::stPlaybackTimer Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::updateTrackBlock Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrackBlock Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 26 Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::serviceStop Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 26 Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreCommandRouter::serviceStop Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: ControllerMpd::stop Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: Feb 19 18:51:47 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 75 milliseconds Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index undefined Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 29 Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::startPlaybackTimer Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 29 Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::clearAddPlayTracks USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/030. Deep Purple - You Keep On Movin'.flac Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 1 milliseconds Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 0 milliseconds Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand clear Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: Feb 19 18:51:47 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: sendMpdCommand clear took 1 milliseconds Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/030. Deep Purple - You Keep On Movin'.flac" Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 29 Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 29 Feb 19 18:51:47 zykow-volumio4 volumio[946]: 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":"Rivers Of Glory","artist":"Bonfire","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/027. Bonfire - Rivers Of Glory.flac","trackType":"flac"} Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 29 Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService stop Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 29 Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: No code Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 29 Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: ------------------------------ 20ms Feb 19 18:51:47 zykow-volumio4 volumio[946]: error: updateQueue error: null Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: Feb 19 18:51:47 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: ------------------------------ 18ms Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/030. Deep Purple - You Keep On Movin'.flac" took 17 milliseconds Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand play Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: ------------------------------ 8ms Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: sendMpdCommand play took 7 milliseconds Feb 19 18:51:47 zykow-volumio4 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: Feb 19 18:51:47 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: Feb 19 18:51:47 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 16 milliseconds Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 1 milliseconds Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 29 Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"You Keep On Movin'","artist":"Deep Purple","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/030. Deep Purple - You Keep On Movin'.flac","trackType":"flac"} Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 29 Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: ------------------------------ 32ms Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 29 Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"You Keep On Movin'","artist":"Deep Purple","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/030. Deep Purple - You Keep On Movin'.flac","trackType":"flac"} Feb 19 18:51:47 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 29 Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus play Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: Received an update from plugin. extracting info from payload Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 29 Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 29 Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:47 zykow-volumio4 volumio[946]: info: ------------------------------ 26ms Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPlay Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index 37 Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::stop Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::stPlaybackTimer Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::updateTrackBlock Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrackBlock Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 29 Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::serviceStop Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 29 Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreCommandRouter::serviceStop Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: ControllerMpd::stop Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: Feb 19 18:51:58 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 62 milliseconds Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index undefined Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 37 Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::startPlaybackTimer Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 37 Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::clearAddPlayTracks USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/038. Scorpions - Always Somewhere.flac Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 2 milliseconds Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 2 milliseconds Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand clear Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: Feb 19 18:51:58 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: sendMpdCommand clear took 1 milliseconds Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/038. Scorpions - Always Somewhere.flac" Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 37 Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 37 Feb 19 18:51:58 zykow-volumio4 volumio[946]: 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":"You Keep On Movin'","artist":"Deep Purple","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/030. Deep Purple - You Keep On Movin'.flac","trackType":"flac"} Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 37 Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService stop Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 37 Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: No code Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 37 Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: ------------------------------ 18ms Feb 19 18:51:58 zykow-volumio4 volumio[946]: error: updateQueue error: null Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: Feb 19 18:51:58 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: ------------------------------ 15ms Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/038. Scorpions - Always Somewhere.flac" took 14 milliseconds Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand play Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: ------------------------------ 5ms Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: sendMpdCommand play took 4 milliseconds Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: Feb 19 18:51:58 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:58 zykow-volumio4 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: Feb 19 18:51:58 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 13 milliseconds Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 1 milliseconds Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 37 Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Always Somewhere","artist":"Scorpions","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/038. Scorpions - Always Somewhere.flac","trackType":"flac"} Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 37 Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: ------------------------------ 20ms Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 37 Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Always Somewhere","artist":"Scorpions","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/038. Scorpions - Always Somewhere.flac","trackType":"flac"} Feb 19 18:51:58 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 37 Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus play Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: Received an update from plugin. extracting info from payload Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 37 Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 37 Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:51:58 zykow-volumio4 volumio[946]: info: ------------------------------ 16ms Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPlay Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index 43 Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::stop Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::stPlaybackTimer Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::updateTrackBlock Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrackBlock Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 37 Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::serviceStop Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 37 Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreCommandRouter::serviceStop Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: ControllerMpd::stop Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: Feb 19 18:52:04 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 89 milliseconds Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::play index undefined Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 43 Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::startPlaybackTimer Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 43 Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::clearAddPlayTracks USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/044. Deep Purple - Wasted Sunsets.flac Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 2 milliseconds Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: sendMpdCommand stop took 1 milliseconds Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand clear Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: Feb 19 18:52:04 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: sendMpdCommand clear took 2 milliseconds Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/044. Deep Purple - Wasted Sunsets.flac" Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 43 Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 43 Feb 19 18:52:04 zykow-volumio4 volumio[946]: 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":"Always Somewhere","artist":"Scorpions","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/038. Scorpions - Always Somewhere.flac","trackType":"flac"} Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 43 Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService stop Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 43 Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: No code Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 43 Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: ------------------------------ 24ms Feb 19 18:52:04 zykow-volumio4 volumio[946]: error: updateQueue error: null Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: Feb 19 18:52:04 zykow-volumio4 volumio[946]: ---------------------------- MPD announces system playlist update Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: Ignoring MPD Status Update Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: ------------------------------ 22ms Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: sendMpdCommand add "USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/044. Deep Purple - Wasted Sunsets.flac" took 22 milliseconds Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand play Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: ------------------------------ 11ms Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: sendMpdCommand play took 8 milliseconds Feb 19 18:52:04 zykow-volumio4 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: Feb 19 18:52:04 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: Feb 19 18:52:04 zykow-volumio4 volumio[946]: ---------------------------- MPD announces state update: player Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: ControllerMpd::getState Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand status Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 1 milliseconds Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: sendMpdCommand status took 17 milliseconds Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseState Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 43 Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"821 Kbps","isStreaming":false,"title":"Wasted Sunsets","artist":"Deep Purple","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/044. Deep Purple - Wasted Sunsets.flac","trackType":"flac"} Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 43 Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus stop Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: ------------------------------ 20ms Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: ControllerMpd::parseTrackInfo Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: ControllerMpd::pushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreCommandRouter::servicePushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 43 Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"821 Kbps","isStreaming":false,"title":"Wasted Sunsets","artist":"Deep Purple","album":"100 Rock Ballads Vol.4 (2019)","uri":"USB/Z_1TBT_LITTLE/FLAC/_ROCK/100 Rock Ballads Vol.4 (2019) FLAC/044. Deep Purple - Wasted Sunsets.flac","trackType":"flac"} Feb 19 18:52:04 zykow-volumio4 volumio[946]: verbose: CURRENT POSITION 43 Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState stateService play Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::syncState currentStatus play Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: Received an update from plugin. extracting info from payload Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 43 Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreStateMachine::pushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CorePlayQueue::getTrack 43 Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushState Feb 19 18:52:04 zykow-volumio4 volumio[946]: info: ------------------------------ 24ms Feb 19 18:52:09 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioMoveQueue Feb 19 18:52:09 zykow-volumio4 volumio[946]: info: CoreStateMachine::moveQueueItem 42 42 Feb 19 18:52:09 zykow-volumio4 volumio[946]: info: CorePlayQueue::moveQueueItem 42 --> 42 Feb 19 18:52:09 zykow-volumio4 volumio[946]: info: CoreCommandRouter::volumioPushQueue Feb 19 18:52:18 zykow-volumio4 volumio[946]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 18:52:18 zykow-volumio4 volumio[946]: Error: read ECONNRESET Feb 19 18:52:18 zykow-volumio4 volumio[946]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Feb 19 18:52:18 zykow-volumio4 volumio[946]: errno: -104, Feb 19 18:52:18 zykow-volumio4 volumio[946]: code: 'ECONNRESET', Feb 19 18:52:18 zykow-volumio4 volumio[946]: syscall: 'read' Feb 19 18:52:18 zykow-volumio4 volumio[946]: } Feb 19 18:52:19 zykow-volumio4 volumio[946]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 18:52:19 zykow-volumio4 sudo[23834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-19 18:51 Feb 19 18:52:19 zykow-volumio4 sudo[23834]: 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"