Mar 28 22:51:03 volumio volumio[1456]: info: CoreCommandRouter::volumioGetState Mar 28 22:51:03 volumio volumio[1456]: info: CorePlayQueue::getTrack 16 Mar 28 22:51:18 volumio volumio[1456]: info: Preload queue cleared Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::ClearQueue Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::stop Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::stPlaybackTimer Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::updateTrackBlock Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::getTrackBlock Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::getTrack 16 Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::serviceStop Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::getTrack 16 Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::serviceStop Mar 28 22:51:18 volumio volumio[1456]: info: ControllerMpd::stop Mar 28 22:51:18 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::clearPlayQueue Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::saveQueue Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::volumioPushQueue Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::addQueueItems Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::addQueueItems Mar 28 22:51:18 volumio volumio[1456]: info: Preload queue cleared Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/01 Help the Poor B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/01 Help the Poor B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/02 So Excited B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/02 So Excited B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/03 Broken Heart B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/03 Broken Heart B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/04 Ghetto Woman B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/04 Ghetto Woman B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/05 Ain't Nobody Home B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/05 Ain't Nobody Home B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/06 Darlin' You Know I Love You B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/06 Darlin' You Know I Love You B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/07 In the Midnight Hour B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/07 In the Midnight Hour B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/08 Into the Night B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/08 Into the Night B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/09 My Lucille B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/09 My Lucille B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/10 The Blues Come over Me B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/10 The Blues Come over Me B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/11 Since I Met You Baby B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/11 Since I Met You Baby B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/12 I'm Moving On B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/12 I'm Moving On B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::volumioPushQueue Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::saveQueue Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::updateTrackBlock Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::getTrackBlock Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::volumioPlay Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::play index 15 Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::addQueueItems Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::addQueueItems Mar 28 22:51:18 volumio volumio[1456]: info: Preload queue cleared Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/17 Caldonia [Live] B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/17 Caldonia [Live] B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/18 Rock Me Baby [Live] B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/18 Rock Me Baby [Live] B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::stop Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::volumioPushQueue Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::saveQueue Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::play index undefined Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::updateTrackBlock Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::getTrackBlock Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::startPlaybackTimer Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:18 volumio volumio[1456]: verbose: ControllerMpd::clearAddPlayTracks NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3 Mar 28 22:51:18 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 22:51:18 volumio volumio[1456]: info: Mar 28 22:51:18 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:18 volumio volumio[1456]: info: sendMpdCommand stop took 15 milliseconds Mar 28 22:51:18 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:18 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:18 volumio volumio[1456]: info: sendMpdCommand stop took 9 milliseconds Mar 28 22:51:18 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 22:51:18 volumio volumio[1456]: info: Mar 28 22:51:18 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:18 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:18 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:18 volumio volumio[1456]: info: Mar 28 22:51:18 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:18 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:18 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:18 volumio volumio[1456]: info: Mar 28 22:51:18 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:18 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:18 volumio volumio[1456]: info: sendMpdCommand status took 1 milliseconds Mar 28 22:51:18 volumio volumio[1456]: info: sendMpdCommand clear took 1 milliseconds Mar 28 22:51:18 volumio volumio[1456]: info: sendMpdCommand status took 1 milliseconds Mar 28 22:51:18 volumio volumio[1456]: info: sendMpdCommand status took 1 milliseconds Mar 28 22:51:18 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:18 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:18 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand add "NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3" Mar 28 22:51:18 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:18 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:18 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:18 volumio volumio[1456]: 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} Mar 28 22:51:18 volumio volumio[1456]: verbose: CURRENT POSITION 15 Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::syncState stateService stop Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus stop Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:18 volumio volumio[1456]: info: No code Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:18 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:18 volumio volumio[1456]: 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} Mar 28 22:51:18 volumio volumio[1456]: verbose: CURRENT POSITION 15 Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::syncState stateService stop Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus stop Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:18 volumio volumio[1456]: info: No code Mar 28 22:51:18 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:18 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:18 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:18 volumio volumio[1456]: info: ------------------------------ 8ms Mar 28 22:51:18 volumio volumio[1456]: info: ------------------------------ 8ms Mar 28 22:51:18 volumio volumio[1456]: info: Mar 28 22:51:18 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:18 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:18 volumio volumio[1456]: info: Mar 28 22:51:18 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:18 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:18 volumio volumio[1456]: error: updateQueue error: null Mar 28 22:51:18 volumio volumio[1456]: info: Mar 28 22:51:18 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:18 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:18 volumio volumio[1456]: info: ------------------------------ 8ms Mar 28 22:51:18 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 28 22:51:18 volumio volumio[1456]: info: sendMpdCommand add "NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3" took 8 milliseconds Mar 28 22:51:18 volumio volumio[1456]: info: ------------------------------ 1ms Mar 28 22:51:18 volumio volumio[1456]: info: ------------------------------ 1ms Mar 28 22:51:18 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:18 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand play Mar 28 22:51:18 volumio volumio[1456]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 28 22:51:18 volumio volumio[1456]: info: ------------------------------ 9ms Mar 28 22:51:18 volumio volumio[1456]: info: Mar 28 22:51:18 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:18 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:18 volumio volumio[1456]: info: Mar 28 22:51:18 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:18 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:18 volumio volumio[1456]: info: ------------------------------ 3ms Mar 28 22:51:18 volumio volumio[1456]: info: sendMpdCommand play took 3 milliseconds Mar 28 22:51:18 volumio volumio[1456]: info: ------------------------------ 3ms Mar 28 22:51:18 volumio volumio[1456]: info: ------------------------------ 2ms Mar 28 22:51:19 volumio volumio[1456]: info: Mar 28 22:51:19 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:19 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:19 volumio volumio[1456]: info: Mar 28 22:51:19 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:19 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:19 volumio volumio[1456]: info: Mar 28 22:51:19 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:19 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:19 volumio volumio[1456]: info: Mar 28 22:51:19 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:19 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:19 volumio volumio[1456]: info: Mar 28 22:51:19 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:19 volumio volumio[1456]: info: sendMpdCommand status took 3 milliseconds Mar 28 22:51:19 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:19 volumio volumio[1456]: info: sendMpdCommand status took 4 milliseconds Mar 28 22:51:19 volumio volumio[1456]: info: sendMpdCommand status took 3 milliseconds Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:19 volumio volumio[1456]: info: Mar 28 22:51:19 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:19 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:19 volumio volumio[1456]: info: sendMpdCommand status took 2 milliseconds Mar 28 22:51:19 volumio volumio[1456]: info: sendMpdCommand status took 2 milliseconds Mar 28 22:51:19 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 22:51:19 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 22:51:19 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 22:51:19 volumio volumio[1456]: info: sendMpdCommand status took 1 milliseconds Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:19 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:19 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:19 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Please Love Me [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:19 volumio volumio[1456]: verbose: CURRENT POSITION 15 Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus stop Mar 28 22:51:19 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:19 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:19 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Please Love Me [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:19 volumio volumio[1456]: verbose: CURRENT POSITION 15 Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:19 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:19 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:19 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:19 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:19 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:19 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Please Love Me [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:19 volumio volumio[1456]: verbose: CURRENT POSITION 15 Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:19 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:19 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:19 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:19 volumio volumio[1456]: info: ------------------------------ 8ms Mar 28 22:51:19 volumio volumio[1456]: info: ------------------------------ 10ms Mar 28 22:51:19 volumio volumio[1456]: info: ------------------------------ 10ms Mar 28 22:51:19 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 28 22:51:19 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 28 22:51:19 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:19 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:19 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:19 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:19 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Please Love Me [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:19 volumio volumio[1456]: verbose: CURRENT POSITION 15 Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:19 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:19 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:19 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:19 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:19 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:19 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Please Love Me [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:19 volumio volumio[1456]: verbose: CURRENT POSITION 15 Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:19 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:19 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:19 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:19 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:19 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:19 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Please Love Me [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:19 volumio volumio[1456]: verbose: CURRENT POSITION 15 Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:19 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:19 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:19 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:19 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:19 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:19 volumio volumio[1456]: info: ------------------------------ 13ms Mar 28 22:51:19 volumio volumio[1456]: info: ------------------------------ 13ms Mar 28 22:51:19 volumio volumio[1456]: info: ------------------------------ 12ms Mar 28 22:51:30 volumio volumio[1456]: info: Preload queue cleared Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::ClearQueue Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::stop Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::stPlaybackTimer Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::updateTrackBlock Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrackBlock Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::serviceStop Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 15 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::serviceStop Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::stop Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::clearPlayQueue Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::saveQueue Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushQueue Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::addQueueItems Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::addQueueItems Mar 28 22:51:30 volumio volumio[1456]: info: Preload queue cleared Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/01 Help the Poor B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/01 Help the Poor B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/02 So Excited B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/02 So Excited B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/03 Broken Heart B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/03 Broken Heart B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/04 Ghetto Woman B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/04 Ghetto Woman B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/05 Ain't Nobody Home B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/05 Ain't Nobody Home B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/06 Darlin' You Know I Love You B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/06 Darlin' You Know I Love You B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/07 In the Midnight Hour B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/07 In the Midnight Hour B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/08 Into the Night B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/08 Into the Night B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/09 My Lucille B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/09 My Lucille B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/10 The Blues Come over Me B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/10 The Blues Come over Me B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/11 Since I Met You Baby B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/11 Since I Met You Baby B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/12 I'm Moving On B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/12 I'm Moving On B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushQueue Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::saveQueue Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::updateTrackBlock Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrackBlock Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPlay Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::play index 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::addQueueItems Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::addQueueItems Mar 28 22:51:30 volumio volumio[1456]: info: Preload queue cleared Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/17 Caldonia [Live] B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/17 Caldonia [Live] B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/18 Rock Me Baby [Live] B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/18 Rock Me Baby [Live] B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::stop Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushQueue Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::saveQueue Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::play index undefined Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::updateTrackBlock Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrackBlock Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::startPlaybackTimer Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::clearAddPlayTracks NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3 Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 22:51:30 volumio volumio[1456]: info: Mar 28 22:51:30 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand stop took 19 milliseconds Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand stop took 14 milliseconds Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 22:51:30 volumio volumio[1456]: info: Mar 28 22:51:30 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:30 volumio volumio[1456]: info: Mar 28 22:51:30 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:30 volumio volumio[1456]: info: Mar 28 22:51:30 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:30 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand status took 2 milliseconds Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand clear took 1 milliseconds Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand status took 1 milliseconds Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand status took 1 milliseconds Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand add "NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3" Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: 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} Mar 28 22:51:30 volumio volumio[1456]: verbose: CURRENT POSITION 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::syncState stateService stop Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus stop Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: No code Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: 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} Mar 28 22:51:30 volumio volumio[1456]: verbose: CURRENT POSITION 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::syncState stateService stop Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus stop Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: No code Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: ------------------------------ 17ms Mar 28 22:51:30 volumio volumio[1456]: info: ------------------------------ 18ms Mar 28 22:51:30 volumio volumio[1456]: info: Mar 28 22:51:30 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:30 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:30 volumio volumio[1456]: info: Mar 28 22:51:30 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:30 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:30 volumio volumio[1456]: error: updateQueue error: null Mar 28 22:51:30 volumio volumio[1456]: info: Mar 28 22:51:30 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:30 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:30 volumio volumio[1456]: info: ------------------------------ 22ms Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 22 milliseconds Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand add "NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3" took 23 milliseconds Mar 28 22:51:30 volumio volumio[1456]: info: ------------------------------ 6ms Mar 28 22:51:30 volumio volumio[1456]: info: ------------------------------ 6ms Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand play Mar 28 22:51:30 volumio volumio[1456]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 28 22:51:30 volumio volumio[1456]: info: ------------------------------ 25ms Mar 28 22:51:30 volumio volumio[1456]: info: Mar 28 22:51:30 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:30 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:30 volumio volumio[1456]: info: Mar 28 22:51:30 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:30 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:30 volumio volumio[1456]: info: ------------------------------ 9ms Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand play took 8 milliseconds Mar 28 22:51:30 volumio volumio[1456]: info: ------------------------------ 8ms Mar 28 22:51:30 volumio volumio[1456]: info: ------------------------------ 8ms Mar 28 22:51:30 volumio volumio[1456]: info: Mar 28 22:51:30 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:30 volumio volumio[1456]: info: Mar 28 22:51:30 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:30 volumio volumio[1456]: info: Mar 28 22:51:30 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:30 volumio volumio[1456]: info: Mar 28 22:51:30 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:30 volumio volumio[1456]: info: Mar 28 22:51:30 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand status took 25 milliseconds Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand status took 21 milliseconds Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand status took 21 milliseconds Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:30 volumio volumio[1456]: info: Mar 28 22:51:30 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand status took 19 milliseconds Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand status took 19 milliseconds Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand status took 19 milliseconds Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Three O'Clock Blues [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:30 volumio volumio[1456]: verbose: CURRENT POSITION 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus stop Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Three O'Clock Blues [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:30 volumio volumio[1456]: verbose: CURRENT POSITION 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:30 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Three O'Clock Blues [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:30 volumio volumio[1456]: verbose: CURRENT POSITION 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:30 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: ------------------------------ 48ms Mar 28 22:51:30 volumio volumio[1456]: info: ------------------------------ 45ms Mar 28 22:51:30 volumio volumio[1456]: info: ------------------------------ 45ms Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 28 22:51:30 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:30 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Three O'Clock Blues [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:30 volumio volumio[1456]: verbose: CURRENT POSITION 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:30 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Three O'Clock Blues [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:30 volumio volumio[1456]: verbose: CURRENT POSITION 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:30 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Three O'Clock Blues [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:30 volumio volumio[1456]: verbose: CURRENT POSITION 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:30 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:30 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:30 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:30 volumio volumio[1456]: info: ------------------------------ 32ms Mar 28 22:51:30 volumio volumio[1456]: info: ------------------------------ 32ms Mar 28 22:51:30 volumio volumio[1456]: info: ------------------------------ 31ms Mar 28 22:51:33 volumio volumio[1456]: info: CoreCommandRouter::volumioGetState Mar 28 22:51:33 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:44 volumio wpa_supplicant[1336]: wlan0: WPA: Group rekeying completed with 2c:91:ab:5f:76:be [GTK=CCMP] Mar 28 22:51:47 volumio volumio[1456]: info: Preload queue cleared Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::ClearQueue Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::stop Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::stPlaybackTimer Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::updateTrackBlock Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::getTrackBlock Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::serviceStop Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::getTrack 14 Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::serviceStop Mar 28 22:51:47 volumio volumio[1456]: info: ControllerMpd::stop Mar 28 22:51:47 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::clearPlayQueue Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::saveQueue Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::volumioPushQueue Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::addQueueItems Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::addQueueItems Mar 28 22:51:47 volumio volumio[1456]: info: Preload queue cleared Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/01 Help the Poor B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/01 Help the Poor B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/02 So Excited B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/02 So Excited B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/03 Broken Heart B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/03 Broken Heart B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/04 Ghetto Woman B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/04 Ghetto Woman B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/05 Ain't Nobody Home B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/05 Ain't Nobody Home B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/06 Darlin' You Know I Love You B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/06 Darlin' You Know I Love You B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/07 In the Midnight Hour B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/07 In the Midnight Hour B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/08 Into the Night B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/08 Into the Night B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/09 My Lucille B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/09 My Lucille B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/10 The Blues Come over Me B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/10 The Blues Come over Me B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/11 Since I Met You Baby B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/11 Since I Met You Baby B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/12 I'm Moving On B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/12 I'm Moving On B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::volumioPushQueue Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::saveQueue Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::updateTrackBlock Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::getTrackBlock Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::volumioPlay Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::play index 13 Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::addQueueItems Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::addQueueItems Mar 28 22:51:47 volumio volumio[1456]: info: Preload queue cleared Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/17 Caldonia [Live] B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/17 Caldonia [Live] B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/18 Rock Me Baby [Live] B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/18 Rock Me Baby [Live] B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::stop Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::volumioPushQueue Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::saveQueue Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::play index undefined Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::updateTrackBlock Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::getTrackBlock Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::startPlaybackTimer Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:47 volumio volumio[1456]: verbose: ControllerMpd::clearAddPlayTracks NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3 Mar 28 22:51:47 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 22:51:47 volumio volumio[1456]: info: Mar 28 22:51:47 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:47 volumio volumio[1456]: info: sendMpdCommand stop took 15 milliseconds Mar 28 22:51:47 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:47 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:47 volumio volumio[1456]: info: sendMpdCommand stop took 9 milliseconds Mar 28 22:51:47 volumio volumio[1456]: info: sendMpdCommand status took 0 milliseconds Mar 28 22:51:47 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 22:51:47 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:47 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:47 volumio volumio[1456]: info: Mar 28 22:51:47 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:47 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:47 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:47 volumio volumio[1456]: info: Mar 28 22:51:47 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:47 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:47 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:47 volumio volumio[1456]: info: Mar 28 22:51:47 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:47 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:47 volumio volumio[1456]: info: sendMpdCommand clear took 1 milliseconds Mar 28 22:51:47 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 22:51:47 volumio volumio[1456]: info: sendMpdCommand status took 1 milliseconds Mar 28 22:51:47 volumio volumio[1456]: info: sendMpdCommand status took 1 milliseconds Mar 28 22:51:47 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand add "NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3" Mar 28 22:51:47 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:47 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:47 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:47 volumio volumio[1456]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 28 22:51:47 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:47 volumio volumio[1456]: 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} Mar 28 22:51:47 volumio volumio[1456]: verbose: CURRENT POSITION 13 Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::syncState stateService stop Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus stop Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:47 volumio volumio[1456]: info: No code Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:47 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:47 volumio volumio[1456]: 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} Mar 28 22:51:47 volumio volumio[1456]: verbose: CURRENT POSITION 13 Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::syncState stateService stop Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus stop Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:47 volumio volumio[1456]: info: No code Mar 28 22:51:47 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:47 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:47 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:47 volumio volumio[1456]: info: ------------------------------ 5ms Mar 28 22:51:47 volumio volumio[1456]: info: ------------------------------ 7ms Mar 28 22:51:47 volumio volumio[1456]: info: ------------------------------ 7ms Mar 28 22:51:47 volumio volumio[1456]: info: Mar 28 22:51:47 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:47 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:47 volumio volumio[1456]: info: Mar 28 22:51:47 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:47 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:47 volumio volumio[1456]: error: updateQueue error: null Mar 28 22:51:47 volumio volumio[1456]: info: Mar 28 22:51:47 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:47 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:47 volumio volumio[1456]: info: ------------------------------ 8ms Mar 28 22:51:47 volumio volumio[1456]: info: sendMpdCommand add "NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3" took 7 milliseconds Mar 28 22:51:47 volumio volumio[1456]: info: ------------------------------ 1ms Mar 28 22:51:47 volumio volumio[1456]: info: ------------------------------ 1ms Mar 28 22:51:47 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand play Mar 28 22:51:47 volumio volumio[1456]: info: Mar 28 22:51:47 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:47 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:47 volumio volumio[1456]: info: Mar 28 22:51:47 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:47 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:47 volumio volumio[1456]: info: ------------------------------ 3ms Mar 28 22:51:47 volumio volumio[1456]: info: sendMpdCommand play took 2 milliseconds Mar 28 22:51:47 volumio volumio[1456]: info: ------------------------------ 2ms Mar 28 22:51:47 volumio volumio[1456]: info: ------------------------------ 2ms Mar 28 22:51:48 volumio volumio[1456]: info: Mar 28 22:51:48 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:48 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:48 volumio volumio[1456]: info: Mar 28 22:51:48 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:48 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:48 volumio volumio[1456]: info: Mar 28 22:51:48 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:48 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:48 volumio volumio[1456]: info: Mar 28 22:51:48 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:48 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:48 volumio volumio[1456]: info: Mar 28 22:51:48 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:48 volumio volumio[1456]: info: sendMpdCommand status took 2 milliseconds Mar 28 22:51:48 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:48 volumio volumio[1456]: info: sendMpdCommand status took 2 milliseconds Mar 28 22:51:48 volumio volumio[1456]: info: sendMpdCommand status took 2 milliseconds Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:48 volumio volumio[1456]: info: Mar 28 22:51:48 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:48 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:48 volumio volumio[1456]: info: sendMpdCommand status took 20 milliseconds Mar 28 22:51:48 volumio volumio[1456]: info: sendMpdCommand status took 20 milliseconds Mar 28 22:51:48 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 28 22:51:48 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 28 22:51:48 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 28 22:51:48 volumio volumio[1456]: info: sendMpdCommand status took 19 milliseconds Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:48 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:48 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:48 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":110,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Woke up This Morning (My Baby's Gone) [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:48 volumio volumio[1456]: verbose: CURRENT POSITION 13 Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus stop Mar 28 22:51:48 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:48 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:48 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":110,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Woke up This Morning (My Baby's Gone) [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:48 volumio volumio[1456]: verbose: CURRENT POSITION 13 Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:48 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:48 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:48 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:48 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:48 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:48 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":110,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Woke up This Morning (My Baby's Gone) [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:48 volumio volumio[1456]: verbose: CURRENT POSITION 13 Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:48 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:48 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:48 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:48 volumio volumio[1456]: info: ------------------------------ 26ms Mar 28 22:51:48 volumio volumio[1456]: info: ------------------------------ 27ms Mar 28 22:51:48 volumio volumio[1456]: info: ------------------------------ 27ms Mar 28 22:51:48 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 28 22:51:48 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 28 22:51:48 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:48 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:48 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:48 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:48 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":110,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Woke up This Morning (My Baby's Gone) [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:48 volumio volumio[1456]: verbose: CURRENT POSITION 13 Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:48 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:48 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:48 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:48 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:48 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:48 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":110,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Woke up This Morning (My Baby's Gone) [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:48 volumio volumio[1456]: verbose: CURRENT POSITION 13 Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:48 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:48 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:48 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:48 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:48 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:48 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":110,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Woke up This Morning (My Baby's Gone) [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:48 volumio volumio[1456]: verbose: CURRENT POSITION 13 Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:48 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:48 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:48 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:48 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:48 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:48 volumio volumio[1456]: info: ------------------------------ 33ms Mar 28 22:51:48 volumio volumio[1456]: info: ------------------------------ 33ms Mar 28 22:51:48 volumio volumio[1456]: info: ------------------------------ 32ms Mar 28 22:51:51 volumio volumio[1456]: info: Preload queue cleared Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::ClearQueue Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::stop Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::stPlaybackTimer Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::updateTrackBlock Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrackBlock Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::serviceStop Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 13 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::serviceStop Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::stop Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::clearPlayQueue Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::saveQueue Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushQueue Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::addQueueItems Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::addQueueItems Mar 28 22:51:51 volumio volumio[1456]: info: Preload queue cleared Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/01 Help the Poor B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/01 Help the Poor B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/02 So Excited B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/02 So Excited B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/03 Broken Heart B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/03 Broken Heart B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/04 Ghetto Woman B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/04 Ghetto Woman B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/05 Ain't Nobody Home B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/05 Ain't Nobody Home B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/06 Darlin' You Know I Love You B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/06 Darlin' You Know I Love You B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/07 In the Midnight Hour B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/07 In the Midnight Hour B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/08 Into the Night B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/08 Into the Night B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/09 My Lucille B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/09 My Lucille B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/10 The Blues Come over Me B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/10 The Blues Come over Me B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/11 Since I Met You Baby B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/11 Since I Met You Baby B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/12 I'm Moving On B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/12 I'm Moving On B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushQueue Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::saveQueue Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::updateTrackBlock Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrackBlock Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPlay Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::play index 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::addQueueItems Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::addQueueItems Mar 28 22:51:51 volumio volumio[1456]: info: Preload queue cleared Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/14 Woke up This Morning (My Baby's Gone) [Live] B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/15 Three O'Clock Blues [Live] B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/16 Please Love Me [Live] B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/17 Caldonia [Live] B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/17 Caldonia [Live] B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Adding Item to queue: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/18 Rock Me Baby [Live] B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: Using cached record of: music-library/NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/18 Rock Me Baby [Live] B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::stop Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushQueue Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::saveQueue Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::play index undefined Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::updateTrackBlock Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrackBlock Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::startPlaybackTimer Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::clearAddPlayTracks NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3 Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand stop took 12 milliseconds Mar 28 22:51:51 volumio volumio[1456]: info: Mar 28 22:51:51 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand stop took 7 milliseconds Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 22:51:51 volumio volumio[1456]: info: Mar 28 22:51:51 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:51 volumio volumio[1456]: info: Mar 28 22:51:51 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:51 volumio volumio[1456]: info: Mar 28 22:51:51 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:51 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand status took 1 milliseconds Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand clear took 0 milliseconds Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand add "NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3" Mar 28 22:51:51 volumio volumio[1456]: info: Mar 28 22:51:51 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:51 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:51 volumio volumio[1456]: error: updateQueue error: null Mar 28 22:51:51 volumio volumio[1456]: info: Mar 28 22:51:51 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:51 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand status took 1 milliseconds Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand status took 1 milliseconds Mar 28 22:51:51 volumio volumio[1456]: info: ------------------------------ 1ms Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand add "NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3" took 1 milliseconds Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand play Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: 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} Mar 28 22:51:51 volumio volumio[1456]: verbose: CURRENT POSITION 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::syncState stateService stop Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus stop Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: info: No code Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: 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} Mar 28 22:51:51 volumio volumio[1456]: verbose: CURRENT POSITION 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::syncState stateService stop Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus stop Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: info: No code Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 28 22:51:51 volumio volumio[1456]: info: ------------------------------ 7ms Mar 28 22:51:51 volumio volumio[1456]: info: ------------------------------ 7ms Mar 28 22:51:51 volumio volumio[1456]: info: ------------------------------ 8ms Mar 28 22:51:51 volumio volumio[1456]: info: Mar 28 22:51:51 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:51 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:51 volumio volumio[1456]: info: Mar 28 22:51:51 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:51 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:51 volumio volumio[1456]: info: ------------------------------ 7ms Mar 28 22:51:51 volumio volumio[1456]: info: ------------------------------ 7ms Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand play took 7 milliseconds Mar 28 22:51:51 volumio volumio[1456]: info: ------------------------------ 1ms Mar 28 22:51:51 volumio volumio[1456]: info: ------------------------------ 1ms Mar 28 22:51:51 volumio volumio[1456]: info: Mar 28 22:51:51 volumio volumio[1456]: ---------------------------- MPD announces system playlist update Mar 28 22:51:51 volumio volumio[1456]: info: Ignoring MPD Status Update Mar 28 22:51:51 volumio volumio[1456]: info: ------------------------------ 2ms Mar 28 22:51:51 volumio volumio[1456]: info: Mar 28 22:51:51 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:51 volumio volumio[1456]: info: Mar 28 22:51:51 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:51 volumio volumio[1456]: info: Mar 28 22:51:51 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:51 volumio volumio[1456]: info: Mar 28 22:51:51 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand status took 64 milliseconds Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand status took 62 milliseconds Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand status took 62 milliseconds Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:51 volumio volumio[1456]: info: Mar 28 22:51:51 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:51 volumio volumio[1456]: info: Mar 28 22:51:51 volumio volumio[1456]: ---------------------------- MPD announces state update: player Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::getState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand status Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand status took 25 milliseconds Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 25 milliseconds Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 25 milliseconds Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 25 milliseconds Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand status took 24 milliseconds Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand status took 24 milliseconds Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::parseState Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":331,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Let the Good Times Roll [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:51 volumio volumio[1456]: verbose: CURRENT POSITION 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus stop Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":331,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Let the Good Times Roll [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:51 volumio volumio[1456]: verbose: CURRENT POSITION 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:51 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":331,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Let the Good Times Roll [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:51 volumio volumio[1456]: verbose: CURRENT POSITION 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:51 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: info: ------------------------------ 93ms Mar 28 22:51:51 volumio volumio[1456]: info: ------------------------------ 92ms Mar 28 22:51:51 volumio volumio[1456]: info: ------------------------------ 92ms Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 28 22:51:51 volumio volumio[1456]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:51 volumio volumio[1456]: verbose: ControllerMpd::parseTrackInfo Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":331,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Let the Good Times Roll [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:51 volumio volumio[1456]: verbose: CURRENT POSITION 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:51 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":331,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Let the Good Times Roll [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:51 volumio volumio[1456]: verbose: CURRENT POSITION 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:51 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: info: ControllerMpd::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::servicePushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":331,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Let the Good Times Roll [Live]","artist":"B.B. King","album":"His Definitive Greatest Hits Disc 2","uri":"NAS/Public/Shared Music/B.B. King - HisDefinitiveGreatestHits/CD2/13 Let the Good Times Roll [Live] B.B. King.mp3","trackType":"mp3"} Mar 28 22:51:51 volumio volumio[1456]: verbose: CURRENT POSITION 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::syncState stateService play Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::syncState currentStatus play Mar 28 22:51:51 volumio volumio[1456]: info: Received an update from plugin. extracting info from payload Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: info: CoreStateMachine::pushState Mar 28 22:51:51 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:51:51 volumio volumio[1456]: info: CoreCommandRouter::volumioPushState Mar 28 22:51:51 volumio volumio[1456]: info: ------------------------------ 38ms Mar 28 22:51:51 volumio volumio[1456]: info: ------------------------------ 37ms Mar 28 22:51:51 volumio volumio[1456]: info: ------------------------------ 37ms Mar 28 22:52:03 volumio volumio[1456]: info: CoreCommandRouter::volumioGetState Mar 28 22:52:03 volumio volumio[1456]: info: CorePlayQueue::getTrack 12 Mar 28 22:52:04 volumio volumio[1456]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 22:52:04 volumio volumio[1456]: TypeError: Cannot read properties of null (reading 'slice') Mar 28 22:52:04 volumio volumio[1456]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 28 22:52:04 volumio volumio[1456]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 28 22:52:04 volumio volumio[1456]: at Object.onceWrapper (node:events:629:26) Mar 28 22:52:04 volumio volumio[1456]: at ClientRequest.emit (node:events:514:28) Mar 28 22:52:04 volumio volumio[1456]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Mar 28 22:52:04 volumio volumio[1456]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Mar 28 22:52:04 volumio volumio[1456]: at TLSSocket.socketOnData (node:_http_client:535:22) Mar 28 22:52:04 volumio volumio[1456]: at TLSSocket.emit (node:events:514:28) Mar 28 22:52:04 volumio volumio[1456]: at addChunk (node:internal/streams/readable:343:12) Mar 28 22:52:04 volumio volumio[1456]: at readableAddChunk (node:internal/streams/readable:316:9) Mar 28 22:52:04 volumio volumio[1456]: at Readable.push (node:internal/streams/readable:253:10) Mar 28 22:52:04 volumio volumio[1456]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Mar 28 22:52:04 volumio volumio[1456]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 22:52:04 volumio sudo[13143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 22:51' Mar 28 22:52:04 volumio sudo[13143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"