-- Logs begin at Mon 2025-05-05 22:45:18 -08, end at Mon 2025-05-05 22:57:55 -08. --
May 05 22:56:03 volumio smbd[3292]: [2025/05/05 22:56:03.125387, 0] ../source3/lib/sysquotas.c:461(sys_get_quota)
May 05 22:56:03 volumio smbd[3292]: sys_path_to_bdev() failed for path [FLAC/Layla.jpg]!
May 05 22:56:03 volumio smbd[3292]: [2025/05/05 22:56:03.125859, 0] ../source3/lib/sysquotas.c:461(sys_get_quota)
May 05 22:56:03 volumio smbd[3292]: sys_path_to_bdev() failed for path [FLAC/Layla.jpg]!
May 05 22:56:03 volumio smbd[3292]: [2025/05/05 22:56:03.126231, 0] ../source3/lib/sysquotas.c:461(sys_get_quota)
May 05 22:56:03 volumio smbd[3292]: sys_path_to_bdev() failed for path [FLAC/Layla.jpg]!
May 05 22:56:03 volumio smbd[3292]: [2025/05/05 22:56:03.126599, 0] ../source3/lib/sysquotas.c:461(sys_get_quota)
May 05 22:56:03 volumio smbd[3292]: sys_path_to_bdev() failed for path [FLAC/Layla.jpg]!
May 05 22:56:09 volumio volumio[963]: info: CoreCommandRouter::volumioRandom
May 05 22:56:09 volumio volumio[963]: info: CoreCommandRouter::writePlayerControls
May 05 22:56:09 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:09 volumio volumio[963]: info: CoreStateMachine::setRepeat true single true
May 05 22:56:09 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:09 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:09 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:09 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:10 volumio volumio[963]: info: CoreCommandRouter::volumioRandom
May 05 22:56:10 volumio volumio[963]: info: CoreCommandRouter::writePlayerControls
May 05 22:56:10 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:10 volumio volumio[963]: info: CoreStateMachine::setRepeat false single false
May 05 22:56:10 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:10 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:10 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:10 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: Preload queue cleared
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::ClearQueue
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::stop
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::stPlaybackTimer
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::updateTrackBlock
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrackBlock
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::serviceStop
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::serviceStop
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::stop
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand stop
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::clearPlayQueue
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::saveQueue
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushQueue
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::addQueueItems
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::addQueueItems
May 05 22:56:15 volumio volumio[963]: info: Preload queue cleared
May 05 22:56:15 volumio volumio[963]: info: Adding Item to queue: music-library/NAS/FLAC/Layla.flac
May 05 22:56:15 volumio volumio[963]: info: Using cached record of: music-library/NAS/FLAC/Layla.flac
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushQueue
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::saveQueue
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::updateTrackBlock
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrackBlock
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPlay
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::play index 0
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::stop
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::play index undefined
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::startPlaybackTimer
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Layla.flac
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand stop
May 05 22:56:15 volumio volumio[963]: info:
May 05 22:56:15 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand stop took 44 milliseconds
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand stop took 6 milliseconds
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand clear
May 05 22:56:15 volumio volumio[963]: info:
May 05 22:56:15 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:15 volumio volumio[963]: info:
May 05 22:56:15 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:15 volumio volumio[963]: info:
May 05 22:56:15 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:15 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand status took 14 milliseconds
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand clear took 11 milliseconds
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand status took 6 milliseconds
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand status took 3 milliseconds
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Layla.flac"
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
May 05 22:56:15 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::syncState stateService stop
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: No code
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
May 05 22:56:15 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::syncState stateService stop
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: No code
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: ------------------------------ 81ms
May 05 22:56:15 volumio volumio[963]: info: ------------------------------ 79ms
May 05 22:56:15 volumio volumio[963]: info:
May 05 22:56:15 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:15 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:15 volumio volumio[963]: info:
May 05 22:56:15 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:15 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:15 volumio volumio[963]: error: updateQueue error: null
May 05 22:56:15 volumio volumio[963]: info:
May 05 22:56:15 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:15 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:15 volumio volumio[963]: info: ------------------------------ 86ms
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand playlistinfo took 84 milliseconds
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand add "NAS/FLAC/Layla.flac" took 84 milliseconds
May 05 22:56:15 volumio volumio[963]: info: ------------------------------ 11ms
May 05 22:56:15 volumio volumio[963]: info: ------------------------------ 8ms
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand play
May 05 22:56:15 volumio volumio[963]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
May 05 22:56:15 volumio volumio[963]: info: ------------------------------ 109ms
May 05 22:56:15 volumio volumio[963]: info:
May 05 22:56:15 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:15 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:15 volumio volumio[963]: info:
May 05 22:56:15 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:15 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:15 volumio volumio[963]: info:
May 05 22:56:15 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:15 volumio volumio[963]: info:
May 05 22:56:15 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:15 volumio volumio[963]: info: ------------------------------ 30ms
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand play took 25 milliseconds
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:15 volumio volumio[963]: info: ------------------------------ 19ms
May 05 22:56:15 volumio volumio[963]: info: ------------------------------ 18ms
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:15 volumio volumio[963]: info:
May 05 22:56:15 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:15 volumio volumio[963]: info:
May 05 22:56:15 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand status took 13 milliseconds
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand status took 15 milliseconds
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand status took 11 milliseconds
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand status took 10 milliseconds
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:15 volumio volumio[963]: info:
May 05 22:56:15 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:15 volumio volumio[963]: info:
May 05 22:56:15 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand playlistinfo took 10 milliseconds
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand playlistinfo took 9 milliseconds
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand playlistinfo took 10 milliseconds
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":147,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1184 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:15 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":159,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1184 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:15 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:15 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":569,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1455 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:15 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:15 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":739,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1387 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:15 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:15 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: ------------------------------ 92ms
May 05 22:56:15 volumio volumio[963]: info: ------------------------------ 132ms
May 05 22:56:15 volumio volumio[963]: info: ------------------------------ 126ms
May 05 22:56:15 volumio volumio[963]: info: ------------------------------ 123ms
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand status took 109 milliseconds
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand status took 106 milliseconds
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand playlistinfo took 8 milliseconds
May 05 22:56:15 volumio volumio[963]: info: sendMpdCommand playlistinfo took 8 milliseconds
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:15 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1482 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:15 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:15 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1482 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:15 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:15 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:15 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:15 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:15 volumio volumio[963]: info: ------------------------------ 182ms
May 05 22:56:15 volumio volumio[963]: info: ------------------------------ 179ms
May 05 22:56:17 volumio volumio[963]: info: Preload queue cleared
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::ClearQueue
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::stop
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::stPlaybackTimer
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::updateTrackBlock
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::getTrackBlock
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::serviceStop
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::serviceStop
May 05 22:56:17 volumio volumio[963]: info: ControllerMpd::stop
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand stop
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::clearPlayQueue
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::saveQueue
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::volumioPushQueue
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::addQueueItems
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::addQueueItems
May 05 22:56:17 volumio volumio[963]: info: Preload queue cleared
May 05 22:56:17 volumio volumio[963]: info: Adding Item to queue: music-library/NAS/FLAC/Layla.flac
May 05 22:56:17 volumio volumio[963]: info: Using cached record of: music-library/NAS/FLAC/Layla.flac
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::volumioPushQueue
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::saveQueue
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::updateTrackBlock
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::getTrackBlock
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::volumioPlay
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::play index 0
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::stop
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::play index undefined
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::startPlaybackTimer
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Layla.flac
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand stop
May 05 22:56:17 volumio volumio[963]: info:
May 05 22:56:17 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:17 volumio volumio[963]: info: sendMpdCommand stop took 28 milliseconds
May 05 22:56:17 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:17 volumio volumio[963]: info: sendMpdCommand stop took 5 milliseconds
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand clear
May 05 22:56:17 volumio volumio[963]: info:
May 05 22:56:17 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:17 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:17 volumio volumio[963]: info:
May 05 22:56:17 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:17 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:17 volumio volumio[963]: info:
May 05 22:56:17 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:17 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:17 volumio volumio[963]: info: sendMpdCommand status took 8 milliseconds
May 05 22:56:17 volumio volumio[963]: info: sendMpdCommand clear took 7 milliseconds
May 05 22:56:17 volumio volumio[963]: info: sendMpdCommand status took 6 milliseconds
May 05 22:56:17 volumio volumio[963]: info: sendMpdCommand status took 3 milliseconds
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Layla.flac"
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:17 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:17 volumio volumio[963]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
May 05 22:56:17 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::syncState stateService stop
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:17 volumio volumio[963]: info: No code
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:17 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:17 volumio volumio[963]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
May 05 22:56:17 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::syncState stateService stop
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:17 volumio volumio[963]: info: No code
May 05 22:56:17 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:17 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:17 volumio volumio[963]: info: ------------------------------ 75ms
May 05 22:56:17 volumio volumio[963]: info: ------------------------------ 73ms
May 05 22:56:17 volumio volumio[963]: info:
May 05 22:56:17 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:17 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:17 volumio volumio[963]: info:
May 05 22:56:17 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:17 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:17 volumio volumio[963]: error: updateQueue error: null
May 05 22:56:17 volumio volumio[963]: info:
May 05 22:56:17 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:17 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:17 volumio volumio[963]: info: ------------------------------ 78ms
May 05 22:56:17 volumio volumio[963]: info: sendMpdCommand playlistinfo took 75 milliseconds
May 05 22:56:17 volumio volumio[963]: info: sendMpdCommand add "NAS/FLAC/Layla.flac" took 76 milliseconds
May 05 22:56:17 volumio volumio[963]: info: ------------------------------ 7ms
May 05 22:56:17 volumio volumio[963]: info: ------------------------------ 5ms
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand play
May 05 22:56:17 volumio volumio[963]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
May 05 22:56:17 volumio volumio[963]: info: ------------------------------ 95ms
May 05 22:56:17 volumio volumio[963]: info:
May 05 22:56:17 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:17 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:17 volumio volumio[963]: info:
May 05 22:56:17 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:17 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:17 volumio volumio[963]: info:
May 05 22:56:17 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:17 volumio volumio[963]: info: ------------------------------ 26ms
May 05 22:56:17 volumio volumio[963]: info: sendMpdCommand play took 24 milliseconds
May 05 22:56:17 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:17 volumio volumio[963]: info: ------------------------------ 17ms
May 05 22:56:17 volumio volumio[963]: info: ------------------------------ 16ms
May 05 22:56:17 volumio volumio[963]: info:
May 05 22:56:17 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:17 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:17 volumio volumio[963]: info:
May 05 22:56:17 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:17 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:17 volumio volumio[963]: info: sendMpdCommand status took 19 milliseconds
May 05 22:56:17 volumio volumio[963]: info: sendMpdCommand status took 9 milliseconds
May 05 22:56:17 volumio volumio[963]: info: sendMpdCommand status took 7 milliseconds
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:17 volumio volumio[963]: info: sendMpdCommand playlistinfo took 7 milliseconds
May 05 22:56:17 volumio volumio[963]: info: sendMpdCommand playlistinfo took 8 milliseconds
May 05 22:56:17 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:17 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:17 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:18 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:18 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:18 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:18 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:18 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:18 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:18 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:18 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:18 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:18 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:18 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:18 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:18 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:18 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:18 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:18 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:18 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:18 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:18 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:18 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:18 volumio volumio[963]: info: ------------------------------ 57ms
May 05 22:56:18 volumio volumio[963]: info: ------------------------------ 59ms
May 05 22:56:18 volumio volumio[963]: info: sendMpdCommand playlistinfo took 54 milliseconds
May 05 22:56:18 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:18 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:18 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:18 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:18 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:18 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:18 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:18 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:18 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:18 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:18 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:18 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:18 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:18 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:18 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:18 volumio volumio[963]: info: ------------------------------ 106ms
May 05 22:56:23 volumio volumio[963]: info: CoreCommandRouter::volumioGetState
May 05 22:56:23 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin
May 05 22:56:26 volumio volumio[963]: info: Retrieving Cloud Streaming UI
May 05 22:56:26 volumio volumio[963]: info: Getting Tidal Cloud Configuration
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin
May 05 22:56:26 volumio volumio[963]: info: Getting Qobuz Cloud Configuration
May 05 22:56:26 volumio volumio[963]: info: Asking plugin for UI Config
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin
May 05 22:56:26 volumio volumio[963]: info: Getting Spotify Cloud Configuration
May 05 22:56:26 volumio volumio[963]: info: Asking plugin for UI Config
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin
May 05 22:56:26 volumio volumio[963]: info: Saving Spotify Acccount
May 05 22:56:26 volumio volumio[963]: info: Got Tidal Cloud Configuration
May 05 22:56:26 volumio volumio[963]: info: Got it
May 05 22:56:26 volumio volumio[963]: info: Got it
May 05 22:56:26 volumio volumio[963]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin
May 05 22:56:26 volumio volumio[963]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::volumioGetBrowseSources
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::volumioGetBrowseSources
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::volumioGetBrowseSources
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 05 22:56:26 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
May 05 22:56:27 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand update
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:27 volumio volumio[963]: info:
May 05 22:56:27 volumio volumio[963]: ---------------------------- MPD announces state update: update
May 05 22:56:27 volumio volumio[963]: info: sendMpdCommand update took 9 milliseconds
May 05 22:56:27 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:27 volumio volumio[963]: info:
May 05 22:56:27 volumio volumio[963]: ---------------------------- MPD announces state update: update
May 05 22:56:27 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:27 volumio volumio[963]: info:
May 05 22:56:27 volumio volumio[963]: ---------------------------- MPD announces state update: update
May 05 22:56:27 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:27 volumio volumio[963]: info: sendMpdCommand status took 19 milliseconds
May 05 22:56:27 volumio volumio[963]: info: sendMpdCommand status took 14 milliseconds
May 05 22:56:27 volumio volumio[963]: info: sendMpdCommand status took 12 milliseconds
May 05 22:56:27 volumio volumio[963]: info: sendMpdCommand status took 10 milliseconds
May 05 22:56:27 volumio volumio[963]: info: sendMpdCommand status took 9 milliseconds
May 05 22:56:27 volumio volumio[963]: info: sendMpdCommand status took 8 milliseconds
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:27 volumio volumio[963]: info: Command Router : Notfying DB Updatetrue
May 05 22:56:27 volumio volumio[963]: info: CoreCommandRouter::Close All Modals sent
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:27 volumio volumio[963]: info: Command Router : Notfying DB Updatetrue
May 05 22:56:27 volumio volumio[963]: info: CoreCommandRouter::Close All Modals sent
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:27 volumio volumio[963]: info: Command Router : Notfying DB Updatetrue
May 05 22:56:27 volumio volumio[963]: info: CoreCommandRouter::Close All Modals sent
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:27 volumio volumio[963]: info: sendMpdCommand playlistinfo took 77 milliseconds
May 05 22:56:27 volumio volumio[963]: info: sendMpdCommand playlistinfo took 48 milliseconds
May 05 22:56:27 volumio volumio[963]: info: sendMpdCommand playlistinfo took 23 milliseconds
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:27 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:27 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:27 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:27 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:27 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10734,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1615 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:27 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:27 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:27 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:27 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:27 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:27 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:27 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:27 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:27 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:27 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:27 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:27 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:27 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:27 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:27 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10734,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1615 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:27 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:27 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:27 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:27 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:27 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:27 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:27 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:27 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:27 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:27 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:27 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:27 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:27 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:27 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10734,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1615 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:27 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:27 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:27 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:27 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:27 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:27 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:27 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:27 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:27 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:27 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:27 volumio volumio[963]: info: ------------------------------ 287ms
May 05 22:56:27 volumio volumio[963]: info: ------------------------------ 280ms
May 05 22:56:27 volumio volumio[963]: info: ------------------------------ 277ms
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:28 volumio volumio[963]: info:
May 05 22:56:28 volumio volumio[963]: ---------------------------- MPD announces state update: update
May 05 22:56:28 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:28 volumio volumio[963]: info:
May 05 22:56:28 volumio volumio[963]: ---------------------------- MPD announces state update: update
May 05 22:56:28 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:28 volumio volumio[963]: info:
May 05 22:56:28 volumio volumio[963]: ---------------------------- MPD announces state update: update
May 05 22:56:28 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:28 volumio volumio[963]: info: sendMpdCommand status took 6 milliseconds
May 05 22:56:28 volumio volumio[963]: info: sendMpdCommand status took 5 milliseconds
May 05 22:56:28 volumio volumio[963]: info: sendMpdCommand status took 4 milliseconds
May 05 22:56:28 volumio volumio[963]: info: sendMpdCommand status took 4 milliseconds
May 05 22:56:28 volumio volumio[963]: info: sendMpdCommand status took 3 milliseconds
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:28 volumio volumio[963]: info: Command Router : Notfying DB Updatefalse
May 05 22:56:28 volumio volumio[963]: info: CoreCommandRouter::Close All Modals sent
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:28 volumio volumio[963]: info: Command Router : Notfying DB Updatefalse
May 05 22:56:28 volumio volumio[963]: info: CoreCommandRouter::Close All Modals sent
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:28 volumio volumio[963]: info: Command Router : Notfying DB Updatefalse
May 05 22:56:28 volumio volumio[963]: info: CoreCommandRouter::Close All Modals sent
May 05 22:56:28 volumio volumio[963]: info: sendMpdCommand status took 118 milliseconds
May 05 22:56:28 volumio volumio[963]: info: sendMpdCommand playlistinfo took 82 milliseconds
May 05 22:56:28 volumio volumio[963]: info: sendMpdCommand playlistinfo took 52 milliseconds
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:28 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:28 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:28 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:28 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11986,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1750 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:28 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:28 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:28 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:28 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:28 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:28 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:28 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:28 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:28 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:28 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:28 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:28 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:28 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:28 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:28 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11986,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1750 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:28 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:28 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:28 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:28 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:28 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:28 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:28 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:28 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:28 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:28 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:28 volumio volumio[963]: info: ------------------------------ 198ms
May 05 22:56:28 volumio volumio[963]: info: ------------------------------ 197ms
May 05 22:56:28 volumio volumio[963]: info: sendMpdCommand playlistinfo took 85 milliseconds
May 05 22:56:28 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:28 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:28 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:28 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:28 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11986,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1750 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:28 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:28 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:28 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:28 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:28 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:28 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:28 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:28 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:28 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:28 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:28 volumio volumio[963]: info: ------------------------------ 228ms
May 05 22:56:29 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand rescan
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:29 volumio volumio[963]: info:
May 05 22:56:29 volumio volumio[963]: ---------------------------- MPD announces state update: update
May 05 22:56:29 volumio volumio[963]: info: sendMpdCommand rescan took 2 milliseconds
May 05 22:56:29 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:29 volumio volumio[963]: info:
May 05 22:56:29 volumio volumio[963]: ---------------------------- MPD announces state update: update
May 05 22:56:29 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:29 volumio volumio[963]: info:
May 05 22:56:29 volumio volumio[963]: ---------------------------- MPD announces state update: update
May 05 22:56:29 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:29 volumio volumio[963]: info: sendMpdCommand status took 7 milliseconds
May 05 22:56:29 volumio volumio[963]: info: sendMpdCommand status took 6 milliseconds
May 05 22:56:29 volumio volumio[963]: info: sendMpdCommand status took 5 milliseconds
May 05 22:56:29 volumio volumio[963]: info: sendMpdCommand status took 4 milliseconds
May 05 22:56:29 volumio volumio[963]: info: sendMpdCommand status took 3 milliseconds
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:29 volumio volumio[963]: info: Command Router : Notfying DB Updatetrue
May 05 22:56:29 volumio volumio[963]: info: CoreCommandRouter::Close All Modals sent
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:29 volumio volumio[963]: info: Command Router : Notfying DB Updatetrue
May 05 22:56:29 volumio volumio[963]: info: CoreCommandRouter::Close All Modals sent
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:29 volumio volumio[963]: info: Command Router : Notfying DB Updatetrue
May 05 22:56:29 volumio volumio[963]: info: CoreCommandRouter::Close All Modals sent
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:29 volumio volumio[963]: info: sendMpdCommand playlistinfo took 125 milliseconds
May 05 22:56:29 volumio volumio[963]: info: sendMpdCommand playlistinfo took 90 milliseconds
May 05 22:56:29 volumio volumio[963]: info: sendMpdCommand playlistinfo took 57 milliseconds
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:29 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:29 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:29 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:29 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:29 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12487,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1748 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:29 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:29 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:29 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:29 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:29 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:29 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:29 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:29 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:29 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:29 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:29 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:29 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:29 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:29 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:29 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12487,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1748 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:29 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:29 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:29 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:29 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:29 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:29 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:29 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:29 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:29 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:29 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:29 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:29 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:29 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:29 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12487,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1748 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:29 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:29 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:29 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:29 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:29 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:29 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:29 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:29 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:29 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:29 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:29 volumio volumio[963]: info: ------------------------------ 269ms
May 05 22:56:29 volumio volumio[963]: info: ------------------------------ 268ms
May 05 22:56:29 volumio volumio[963]: info: ------------------------------ 267ms
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand update
May 05 22:56:30 volumio volumio[963]: info: sendMpdCommand update took 4 milliseconds
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 05 22:56:30 volumio volumio[963]: info: MPD Database updated - AlbumList cache refreshed
May 05 22:56:30 volumio volumio[963]: info:
May 05 22:56:30 volumio volumio[963]: ---------------------------- MPD announces state update: database
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:30 volumio volumio[963]: info:
May 05 22:56:30 volumio volumio[963]: ---------------------------- MPD announces state update: update
May 05 22:56:30 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:30 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:30 volumio volumio[963]: info: MPD Database updated - AlbumList cache refreshed
May 05 22:56:30 volumio volumio[963]: info:
May 05 22:56:30 volumio volumio[963]: ---------------------------- MPD announces state update: database
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:30 volumio volumio[963]: info:
May 05 22:56:30 volumio volumio[963]: ---------------------------- MPD announces state update: update
May 05 22:56:30 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:30 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:30 volumio volumio[963]: info: MPD Database updated - AlbumList cache refreshed
May 05 22:56:30 volumio volumio[963]: info:
May 05 22:56:30 volumio volumio[963]: ---------------------------- MPD announces state update: database
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:30 volumio volumio[963]: info:
May 05 22:56:30 volumio volumio[963]: ---------------------------- MPD announces state update: update
May 05 22:56:30 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:30 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:30 volumio volumio[963]: info: sendMpdCommand status took 17 milliseconds
May 05 22:56:30 volumio volumio[963]: info: sendMpdCommand status took 16 milliseconds
May 05 22:56:30 volumio volumio[963]: info: sendMpdCommand status took 17 milliseconds
May 05 22:56:30 volumio volumio[963]: info: sendMpdCommand status took 15 milliseconds
May 05 22:56:30 volumio volumio[963]: info: sendMpdCommand status took 14 milliseconds
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:30 volumio volumio[963]: info: Command Router : Notfying DB Updatetrue
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::Close All Modals sent
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:30 volumio volumio[963]: info: Command Router : Notfying DB Updatetrue
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::Close All Modals sent
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:30 volumio volumio[963]: info: sendMpdCommand status took 129 milliseconds
May 05 22:56:30 volumio volumio[963]: info: sendMpdCommand status took 132 milliseconds
May 05 22:56:30 volumio volumio[963]: info: sendMpdCommand status took 132 milliseconds
May 05 22:56:30 volumio volumio[963]: info: sendMpdCommand playlistinfo took 74 milliseconds
May 05 22:56:30 volumio volumio[963]: info: sendMpdCommand playlistinfo took 75 milliseconds
May 05 22:56:30 volumio volumio[963]: info: sendMpdCommand playlistinfo took 39 milliseconds
May 05 22:56:30 volumio volumio[963]: info: sendMpdCommand playlistinfo took 39 milliseconds
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:30 volumio volumio[963]: info: Command Router : Notfying DB Updatetrue
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::Close All Modals sent
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:30 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:30 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:30 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:30 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13978,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1626 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:30 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:30 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:30 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:30 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:30 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:30 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:30 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:30 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:30 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:30 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:30 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13978,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1626 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:30 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:30 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:30 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:30 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:30 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:30 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:30 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:30 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:30 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:30 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:30 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13978,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1626 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:30 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:30 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:30 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:30 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:30 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:30 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:30 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:30 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:30 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:30 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:30 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13978,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1626 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:30 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:30 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:30 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:30 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:30 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:30 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:30 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:30 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:30 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:30 volumio volumio[963]: info: ------------------------------ 310ms
May 05 22:56:30 volumio volumio[963]: info: ------------------------------ 313ms
May 05 22:56:30 volumio volumio[963]: info: ------------------------------ 312ms
May 05 22:56:31 volumio volumio[963]: info: ------------------------------ 318ms
May 05 22:56:31 volumio volumio[963]: info: sendMpdCommand playlistinfo took 164 milliseconds
May 05 22:56:31 volumio volumio[963]: info: sendMpdCommand playlistinfo took 165 milliseconds
May 05 22:56:31 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:31 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:31 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:31 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:31 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13978,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1626 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:31 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:31 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:31 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:31 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:31 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:31 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:31 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:31 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:31 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:31 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:31 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:31 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:31 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:31 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13978,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1626 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:31 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:31 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:31 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:31 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:31 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:31 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:31 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:31 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:31 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:31 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:31 volumio volumio[963]: info: ------------------------------ 386ms
May 05 22:56:31 volumio volumio[963]: info: ------------------------------ 386ms
May 05 22:56:31 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:31 volumio volumio[963]: info:
May 05 22:56:31 volumio volumio[963]: ---------------------------- MPD announces state update: update
May 05 22:56:31 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:31 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:31 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:31 volumio volumio[963]: info:
May 05 22:56:31 volumio volumio[963]: ---------------------------- MPD announces state update: update
May 05 22:56:31 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:31 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:31 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:31 volumio volumio[963]: info:
May 05 22:56:31 volumio volumio[963]: ---------------------------- MPD announces state update: update
May 05 22:56:31 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:31 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:31 volumio volumio[963]: info: sendMpdCommand status took 6 milliseconds
May 05 22:56:31 volumio volumio[963]: info: sendMpdCommand status took 5 milliseconds
May 05 22:56:31 volumio volumio[963]: info: sendMpdCommand status took 4 milliseconds
May 05 22:56:31 volumio volumio[963]: info: sendMpdCommand status took 3 milliseconds
May 05 22:56:31 volumio volumio[963]: info: sendMpdCommand status took 3 milliseconds
May 05 22:56:31 volumio volumio[963]: info: sendMpdCommand status took 2 milliseconds
May 05 22:56:31 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:31 volumio volumio[963]: info: Command Router : Notfying DB Updatefalse
May 05 22:56:31 volumio volumio[963]: info: CoreCommandRouter::Close All Modals sent
May 05 22:56:31 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:31 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:31 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:32 volumio volumio[963]: info: Command Router : Notfying DB Updatefalse
May 05 22:56:32 volumio volumio[963]: info: CoreCommandRouter::Close All Modals sent
May 05 22:56:32 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:32 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:32 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:32 volumio volumio[963]: info: Command Router : Notfying DB Updatefalse
May 05 22:56:32 volumio volumio[963]: info: CoreCommandRouter::Close All Modals sent
May 05 22:56:32 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:32 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:32 volumio volumio[963]: info: sendMpdCommand playlistinfo took 76 milliseconds
May 05 22:56:32 volumio volumio[963]: info: sendMpdCommand playlistinfo took 47 milliseconds
May 05 22:56:32 volumio volumio[963]: info: sendMpdCommand playlistinfo took 20 milliseconds
May 05 22:56:32 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:32 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:32 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:32 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:32 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:32 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:32 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15230,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1578 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:32 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:32 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:32 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:32 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:32 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:32 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:32 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:32 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:32 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:32 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:32 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:32 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:32 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:32 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:32 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15230,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1578 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:32 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:32 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:32 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:32 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:32 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:32 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:32 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:32 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:32 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:32 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:32 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:32 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:32 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:32 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15230,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1578 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:32 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:32 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:32 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:32 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:32 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:32 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:32 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:32 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:32 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:32 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:32 volumio volumio[963]: info: ------------------------------ 183ms
May 05 22:56:32 volumio volumio[963]: info: ------------------------------ 182ms
May 05 22:56:32 volumio volumio[963]: info: ------------------------------ 184ms
May 05 22:56:34 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 05 22:56:37 volumio volumio[963]: info: CoreCommandRouter::volumioGetState
May 05 22:56:37 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:41 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
May 05 22:56:41 volumio volumio[963]: info: CURURI: music-library
May 05 22:56:41 volumio volumio[963]: info: Preload queue cleared
May 05 22:56:43 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
May 05 22:56:43 volumio volumio[963]: info: CURURI: music-library/NAS
May 05 22:56:43 volumio volumio[963]: info: Preload queue cleared
May 05 22:56:43 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
May 05 22:56:43 volumio volumio[963]: info: CURURI: music-library/NAS/FLAC
May 05 22:56:43 volumio volumio[963]: info: Preload queue cleared
May 05 22:56:44 volumio volumio[963]: info: Preload queue cleared
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::ClearQueue
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::stop
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::stPlaybackTimer
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::updateTrackBlock
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrackBlock
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::serviceStop
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::serviceStop
May 05 22:56:44 volumio volumio[963]: info: ControllerMpd::stop
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand stop
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::clearPlayQueue
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::saveQueue
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioPushQueue
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::addQueueItems
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::addQueueItems
May 05 22:56:44 volumio volumio[963]: info: Preload queue cleared
May 05 22:56:44 volumio volumio[963]: info: Adding Item to queue: music-library/NAS/FLAC/Layla.flac
May 05 22:56:44 volumio volumio[963]: info: Using cached record of: music-library/NAS/FLAC/Layla.flac
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioPushQueue
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::saveQueue
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::updateTrackBlock
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrackBlock
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioPlay
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::play index 0
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::stop
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::play index undefined
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::startPlaybackTimer
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Layla.flac
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand stop
May 05 22:56:44 volumio volumio[963]: info:
May 05 22:56:44 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:44 volumio volumio[963]: info: sendMpdCommand stop took 29 milliseconds
May 05 22:56:44 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:44 volumio volumio[963]: info: sendMpdCommand stop took 4 milliseconds
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand clear
May 05 22:56:44 volumio volumio[963]: info:
May 05 22:56:44 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:44 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:44 volumio volumio[963]: info:
May 05 22:56:44 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:44 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:44 volumio volumio[963]: info:
May 05 22:56:44 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:44 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:44 volumio volumio[963]: info: sendMpdCommand status took 6 milliseconds
May 05 22:56:44 volumio volumio[963]: info: sendMpdCommand clear took 6 milliseconds
May 05 22:56:44 volumio volumio[963]: info: sendMpdCommand status took 4 milliseconds
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Layla.flac"
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:44 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
May 05 22:56:44 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::syncState stateService stop
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:44 volumio volumio[963]: info: No code
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:44 volumio volumio[963]: info: ------------------------------ 44ms
May 05 22:56:44 volumio volumio[963]: info:
May 05 22:56:44 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:44 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:44 volumio volumio[963]: info:
May 05 22:56:44 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:44 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:44 volumio volumio[963]: error: updateQueue error: null
May 05 22:56:44 volumio volumio[963]: info:
May 05 22:56:44 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:44 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:44 volumio volumio[963]: info: sendMpdCommand status took 47 milliseconds
May 05 22:56:44 volumio volumio[963]: info: ------------------------------ 46ms
May 05 22:56:44 volumio volumio[963]: info: sendMpdCommand playlistinfo took 47 milliseconds
May 05 22:56:44 volumio volumio[963]: info: sendMpdCommand add "NAS/FLAC/Layla.flac" took 48 milliseconds
May 05 22:56:44 volumio volumio[963]: info: ------------------------------ 9ms
May 05 22:56:44 volumio volumio[963]: info: ------------------------------ 8ms
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand play
May 05 22:56:44 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
May 05 22:56:44 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::syncState stateService stop
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:44 volumio volumio[963]: info: No code
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:44 volumio volumio[963]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
May 05 22:56:44 volumio volumio[963]: info: ------------------------------ 103ms
May 05 22:56:44 volumio volumio[963]: info: ------------------------------ 108ms
May 05 22:56:44 volumio volumio[963]: info:
May 05 22:56:44 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:44 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:44 volumio volumio[963]: info:
May 05 22:56:44 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:44 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:44 volumio volumio[963]: info:
May 05 22:56:44 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:44 volumio volumio[963]: info:
May 05 22:56:44 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:44 volumio volumio[963]: info: ------------------------------ 78ms
May 05 22:56:44 volumio volumio[963]: info: sendMpdCommand play took 72 milliseconds
May 05 22:56:44 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:44 volumio volumio[963]: info: ------------------------------ 22ms
May 05 22:56:44 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:44 volumio volumio[963]: info: ------------------------------ 17ms
May 05 22:56:44 volumio volumio[963]: info:
May 05 22:56:44 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:44 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:44 volumio volumio[963]: info:
May 05 22:56:44 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:44 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:44 volumio volumio[963]: info: sendMpdCommand status took 9 milliseconds
May 05 22:56:44 volumio volumio[963]: info: sendMpdCommand status took 14 milliseconds
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:44 volumio volumio[963]: info: sendMpdCommand status took 16 milliseconds
May 05 22:56:44 volumio volumio[963]: info: sendMpdCommand playlistinfo took 7 milliseconds
May 05 22:56:44 volumio volumio[963]: info: sendMpdCommand playlistinfo took 6 milliseconds
May 05 22:56:44 volumio volumio[963]: info: sendMpdCommand playlistinfo took 7 milliseconds
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:44 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1482 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:44 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:44 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1482 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:44 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:44 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:44 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1482 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:44 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:44 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:44 volumio volumio[963]: info: ------------------------------ 67ms
May 05 22:56:44 volumio volumio[963]: info: ------------------------------ 92ms
May 05 22:56:44 volumio volumio[963]: info: ------------------------------ 90ms
May 05 22:56:44 volumio volumio[963]: info: sendMpdCommand playlistinfo took 68 milliseconds
May 05 22:56:44 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:44 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1482 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:44 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:44 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:44 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:44 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:44 volumio volumio[963]: info: ------------------------------ 121ms
May 05 22:56:46 volumio volumio[963]: info: Preload queue cleared
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::ClearQueue
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::stop
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::stPlaybackTimer
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::updateTrackBlock
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrackBlock
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::serviceStop
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::serviceStop
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::stop
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand stop
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::clearPlayQueue
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::saveQueue
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushQueue
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::addQueueItems
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::addQueueItems
May 05 22:56:46 volumio volumio[963]: info: Preload queue cleared
May 05 22:56:46 volumio volumio[963]: info: Adding Item to queue: music-library/NAS/FLAC/Layla.flac
May 05 22:56:46 volumio volumio[963]: info: Using cached record of: music-library/NAS/FLAC/Layla.flac
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushQueue
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::saveQueue
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::updateTrackBlock
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrackBlock
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPlay
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::play index 0
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::stop
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::play index undefined
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::startPlaybackTimer
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Layla.flac
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand stop
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand stop took 40 milliseconds
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand stop took 5 milliseconds
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand clear
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:46 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand status took 7 milliseconds
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand clear took 5 milliseconds
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand status took 3 milliseconds
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Layla.flac"
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
May 05 22:56:46 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState stateService stop
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: No code
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
May 05 22:56:46 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState stateService stop
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: No code
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: ------------------------------ 87ms
May 05 22:56:46 volumio volumio[963]: info: ------------------------------ 87ms
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:46 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:46 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:46 volumio volumio[963]: error: updateQueue error: null
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:46 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:46 volumio volumio[963]: info: ------------------------------ 96ms
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand playlistinfo took 94 milliseconds
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand add "NAS/FLAC/Layla.flac" took 94 milliseconds
May 05 22:56:46 volumio volumio[963]: info: ------------------------------ 10ms
May 05 22:56:46 volumio volumio[963]: info: ------------------------------ 7ms
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand play
May 05 22:56:46 volumio volumio[963]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
May 05 22:56:46 volumio volumio[963]: info: ------------------------------ 106ms
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:46 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:46 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:46 volumio volumio[963]: info: ------------------------------ 19ms
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand play took 15 milliseconds
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:46 volumio volumio[963]: info: ------------------------------ 15ms
May 05 22:56:46 volumio volumio[963]: info: ------------------------------ 13ms
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand status took 7 milliseconds
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand status took 17 milliseconds
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand status took 17 milliseconds
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand status took 15 milliseconds
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand playlistinfo took 9 milliseconds
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand status took 6 milliseconds
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:46 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:46 volumio volumio[963]: info: ------------------------------ 34ms
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand playlistinfo took 8 milliseconds
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand playlistinfo took 8 milliseconds
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand playlistinfo took 9 milliseconds
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand playlistinfo took 9 milliseconds
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand status took 5 milliseconds
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":273,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1455 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:46 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:46 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":603,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1273 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:46 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:46 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1273 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:46 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:46 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:46 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:46 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: ------------------------------ 175ms
May 05 22:56:46 volumio volumio[963]: info: ------------------------------ 173ms
May 05 22:56:46 volumio volumio[963]: info: ------------------------------ 174ms
May 05 22:56:46 volumio volumio[963]: info: ------------------------------ 166ms
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand playlistinfo took 145 milliseconds
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1482 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:46 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:46 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: ------------------------------ 193ms
May 05 22:56:46 volumio volumio[963]: info: Preload queue cleared
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::ClearQueue
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::stop
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::stPlaybackTimer
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::updateTrackBlock
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrackBlock
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::serviceStop
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::serviceStop
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::stop
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand stop
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::clearPlayQueue
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::saveQueue
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushQueue
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::addQueueItems
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::addQueueItems
May 05 22:56:46 volumio volumio[963]: info: Preload queue cleared
May 05 22:56:46 volumio volumio[963]: info: Adding Item to queue: music-library/NAS/FLAC/Layla.flac
May 05 22:56:46 volumio volumio[963]: info: Using cached record of: music-library/NAS/FLAC/Layla.flac
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushQueue
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::saveQueue
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::updateTrackBlock
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrackBlock
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPlay
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::play index 0
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::stop
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::play index undefined
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::startPlaybackTimer
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Layla.flac
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand stop
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand stop took 26 milliseconds
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand stop took 5 milliseconds
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand clear
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:46 volumio volumio[963]: info:
May 05 22:56:46 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:46 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand status took 5 milliseconds
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand clear took 4 milliseconds
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand status took 4 milliseconds
May 05 22:56:46 volumio volumio[963]: info: sendMpdCommand status took 2 milliseconds
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Layla.flac"
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:46 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
May 05 22:56:46 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState stateService stop
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: No code
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
May 05 22:56:46 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState stateService stop
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:46 volumio volumio[963]: info: No code
May 05 22:56:46 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:46 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:46 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 74ms
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 75ms
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:47 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:47 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:47 volumio volumio[963]: error: updateQueue error: null
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:47 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 83ms
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand playlistinfo took 83 milliseconds
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand add "NAS/FLAC/Layla.flac" took 84 milliseconds
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 12ms
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 9ms
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand play
May 05 22:56:47 volumio volumio[963]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 100ms
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:47 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:47 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 26ms
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand play took 20 milliseconds
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 12ms
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand status took 20 milliseconds
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand status took 19 milliseconds
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand status took 14 milliseconds
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand status took 5 milliseconds
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:47 volumio volumio[963]: info: Preload queue cleared
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::ClearQueue
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::stop
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::clearPlayQueue
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::saveQueue
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushQueue
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::addQueueItems
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::addQueueItems
May 05 22:56:47 volumio volumio[963]: info: Preload queue cleared
May 05 22:56:47 volumio volumio[963]: info: Adding Item to queue: music-library/NAS/FLAC/Layla.flac
May 05 22:56:47 volumio volumio[963]: info: Using cached record of: music-library/NAS/FLAC/Layla.flac
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushQueue
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::saveQueue
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::updateTrackBlock
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrackBlock
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPlay
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::play index 0
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::stop
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::play index undefined
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::startPlaybackTimer
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Layla.flac
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand stop
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand playlistinfo took 45 milliseconds
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand playlistinfo took 46 milliseconds
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand playlistinfo took 48 milliseconds
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand status took 37 milliseconds
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand status took 51 milliseconds
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:47 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:47 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:47 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":352,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1209 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:47 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:47 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1455 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:47 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:47 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 126ms
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 168ms
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 167ms
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 161ms
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand stop took 115 milliseconds
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand playlistinfo took 92 milliseconds
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand playlistinfo took 92 milliseconds
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand clear
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1482 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:47 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:47 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1482 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:47 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:47 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 197ms
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 191ms
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:47 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand status took 53 milliseconds
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand clear took 51 milliseconds
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand status took 5 milliseconds
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand status took 3 milliseconds
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Layla.flac"
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
May 05 22:56:47 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState stateService stop
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::stPlaybackTimer
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
May 05 22:56:47 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState stateService stop
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: No code
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 82ms
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 81ms
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:47 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:47 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:47 volumio volumio[963]: error: updateQueue error: null
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:47 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 171ms
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand playlistinfo took 187 milliseconds
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand add "NAS/FLAC/Layla.flac" took 188 milliseconds
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 89ms
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 87ms
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand play
May 05 22:56:47 volumio volumio[963]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 261ms
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:47 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces system playlist update
May 05 22:56:47 volumio volumio[963]: info: Ignoring MPD Status Update
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 58ms
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand play took 54 milliseconds
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 47ms
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 46ms
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:47 volumio volumio[963]: info:
May 05 22:56:47 volumio volumio[963]: ---------------------------- MPD announces state update: player
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::getState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand status took 39 milliseconds
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand status took 45 milliseconds
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand status took 54 milliseconds
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand playlistinfo took 7 milliseconds
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand playlistinfo took 2 milliseconds
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseState
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1565 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:47 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1511 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:47 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:47 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 229ms
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 225ms
May 05 22:56:47 volumio volumio[963]: info: sendMpdCommand playlistinfo took 167 milliseconds
May 05 22:56:47 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo
May 05 22:56:47 volumio volumio[963]: info: ControllerMpd::pushState
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::servicePushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1482 Kbps","isStreaming":false,"title":"Layla","artist":"Marcin","album":"Layla","uri":"NAS/FLAC/Layla.flac","trackType":"flac"}
May 05 22:56:47 volumio volumio[963]: verbose: CURRENT POSITION 0
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState stateService play
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus play
May 05 22:56:47 volumio volumio[963]: info: Received an update from plugin. extracting info from payload
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: CoreStateMachine::pushState
May 05 22:56:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:47 volumio volumio[963]: info: CoreCommandRouter::volumioPushState
May 05 22:56:47 volumio volumio[963]: info: ------------------------------ 261ms
May 05 22:56:49 volumio volumio[963]: info: CoreCommandRouter::volumioGetState
May 05 22:56:49 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:54 volumio sudo[3736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 05 22:56:54 volumio sudo[3736]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 05 22:56:54 volumio sudo[3736]: pam_unix(sudo:session): session closed for user root
May 05 22:56:54 volumio sudo[3738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 05 22:56:54 volumio sudo[3738]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 05 22:56:54 volumio sudo[3738]: pam_unix(sudo:session): session closed for user root
May 05 22:56:54 volumio volumio[963]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.10 UA: Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/104.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
May 05 22:56:54 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 05 22:56:54 volumio volumio[963]: info: CoreCommandRouter::volumioGetVisibleSources
May 05 22:56:54 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 05 22:56:54 volumio volumio[963]: info: CoreCommandRouter::volumioGetState
May 05 22:56:54 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:54 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 05 22:56:54 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 05 22:56:54 volumio volumio[963]: info: Received Get System Info
May 05 22:56:54 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 05 22:56:54 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 05 22:56:54 volumio volumio[963]: info: Discovery: Getting this device information
May 05 22:56:54 volumio volumio[963]: info: CoreCommandRouter::volumioGetState
May 05 22:56:54 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:54 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 05 22:56:54 volumio volumio[963]: info: CoreCommandRouter::volumioGetState
May 05 22:56:54 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:54 volumio volumio[963]: info: Listing playlists
May 05 22:56:54 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
May 05 22:56:54 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 05 22:56:54 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
May 05 22:56:55 volumio volumio[963]: info: CoreCommandRouter::volumioGetState
May 05 22:56:55 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:56 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 05 22:56:56 volumio volumio[963]: info: Received Get System Info
May 05 22:56:56 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 05 22:56:56 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 05 22:56:56 volumio volumio[963]: info: Discovery: Getting this device information
May 05 22:56:56 volumio volumio[963]: info: CoreCommandRouter::volumioGetState
May 05 22:56:56 volumio volumio[963]: info: CorePlayQueue::getTrack 0
May 05 22:56:56 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 05 22:56:56 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
May 05 22:57:04 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 05 22:57:55 volumio volumio[963]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 05 22:57:55 volumio volumio[963]: Error: connect ETIMEDOUT 162.125.18.133:443
May 05 22:57:55 volumio volumio[963]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) {
May 05 22:57:55 volumio volumio[963]: errno: -110,
May 05 22:57:55 volumio volumio[963]: code: 'ETIMEDOUT',
May 05 22:57:55 volumio volumio[963]: syscall: 'connect',
May 05 22:57:55 volumio volumio[963]: address: '162.125.18.133',
May 05 22:57:55 volumio volumio[963]: port: 443
May 05 22:57:55 volumio volumio[963]: }
May 05 22:57:55 volumio volumio[963]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 05 22:57:55 volumio sudo[4010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-05 22:56
May 05 22:57:55 volumio sudo[4010]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 10 (buster)"
NAME="Debian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST"
VOLUMIO_VERSION="3.804"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"