-- 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"