-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Fri 2025-07-04 20:19:43 +07. -- Jul 04 20:18:00 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 20:18:00 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 20:18:00 volumio volumio[1198]: info: Discovery: Getting this device information Jul 04 20:18:00 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Jul 04 20:18:00 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 20:18:04 volumio kernel: mmc0: running CQE recovery Jul 04 20:18:09 volumio kernel: mmc0: running CQE recovery Jul 04 20:18:12 volumio kernel: mmc0: running CQE recovery Jul 04 20:18:14 volumio volumio[1198]: info: VolumeController::SetAlsaVolume13 Jul 04 20:18:14 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:14 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:14 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:14 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:14 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:14 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:14 volumio kernel: mmc0: running CQE recovery Jul 04 20:18:14 volumio volumio[1198]: info: VolumeController::SetAlsaVolume14 Jul 04 20:18:14 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:14 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:14 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:14 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:14 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:14 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:14 volumio volumio[1198]: info: VolumeController::SetAlsaVolume15 Jul 04 20:18:14 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:14 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:14 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:14 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:14 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:14 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:15 volumio volumio[1198]: info: VolumeController::SetAlsaVolume16 Jul 04 20:18:15 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:15 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:15 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:15 volumio volumio[1198]: info: VolumeController::SetAlsaVolume17 Jul 04 20:18:15 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:15 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:15 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:15 volumio volumio[1198]: info: VolumeController::SetAlsaVolume18 Jul 04 20:18:15 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:15 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:15 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:15 volumio volumio[1198]: info: VolumeController::SetAlsaVolume19 Jul 04 20:18:15 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:15 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:15 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:15 volumio volumio[1198]: info: VolumeController::SetAlsaVolume20 Jul 04 20:18:15 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:15 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:15 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:15 volumio volumio[1198]: info: VolumeController::SetAlsaVolume21 Jul 04 20:18:15 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:15 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:15 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:15 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:16 volumio volumio[1198]: info: VolumeController::SetAlsaVolume22 Jul 04 20:18:16 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:16 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:16 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:16 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:16 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:16 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:16 volumio volumio[1198]: info: VolumeController::SetAlsaVolume23 Jul 04 20:18:16 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:16 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:16 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:16 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:16 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:16 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:16 volumio volumio[1198]: info: VolumeController::SetAlsaVolume24 Jul 04 20:18:16 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:16 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:16 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:16 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:16 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:16 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:16 volumio volumio[1198]: info: VolumeController::SetAlsaVolume25 Jul 04 20:18:16 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:16 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:16 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:16 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:16 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:16 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:16 volumio volumio[1198]: info: VolumeController::SetAlsaVolume26 Jul 04 20:18:16 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:16 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:16 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:16 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:16 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:16 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:17 volumio volumio[1198]: info: VolumeController::SetAlsaVolume27 Jul 04 20:18:17 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:17 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:17 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:17 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:17 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:17 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:17 volumio volumio[1198]: info: VolumeController::SetAlsaVolume28 Jul 04 20:18:17 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:17 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:17 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:17 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:17 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:17 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:17 volumio volumio[1198]: info: VolumeController::SetAlsaVolume29 Jul 04 20:18:17 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:17 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:17 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:17 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:17 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:17 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:17 volumio volumio[1198]: info: VolumeController::SetAlsaVolume30 Jul 04 20:18:17 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:17 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:17 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:17 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:17 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:17 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:19 volumio volumio[1198]: info: VolumeController::SetAlsaVolume29 Jul 04 20:18:19 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:19 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:19 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:19 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:19 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:19 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:19 volumio kernel: mmc0: running CQE recovery Jul 04 20:18:19 volumio volumio[1198]: info: VolumeController::SetAlsaVolume28 Jul 04 20:18:19 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:19 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:19 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:19 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:19 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:19 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:20 volumio kernel: mmc0: running CQE recovery Jul 04 20:18:20 volumio volumio[1198]: info: VolumeController::SetAlsaVolume27 Jul 04 20:18:20 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:20 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:20 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:20 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:20 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:20 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:20 volumio volumio[1198]: info: VolumeController::SetAlsaVolume26 Jul 04 20:18:20 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:20 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:20 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:20 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:20 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:20 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:20 volumio volumio[1198]: info: VolumeController::SetAlsaVolume25 Jul 04 20:18:20 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:20 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:20 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:20 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:20 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:20 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:20 volumio volumio[1198]: info: VolumeController::SetAlsaVolume24 Jul 04 20:18:20 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:20 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:20 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:20 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:20 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:20 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:25 volumio kernel: mmc0: running CQE recovery Jul 04 20:18:27 volumio kernel: mmc0: running CQE recovery Jul 04 20:18:30 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 20:18:30 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 20:18:30 volumio volumio[1198]: info: Discovery: Getting this device information Jul 04 20:18:30 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Jul 04 20:18:30 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 20:18:32 volumio kernel: mmc0: running CQE recovery Jul 04 20:18:36 volumio volumio[1198]: info: VolumeController::SetAlsaVolume23 Jul 04 20:18:36 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:36 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:36 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:36 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:36 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:36 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:36 volumio kernel: mmc0: running CQE recovery Jul 04 20:18:36 volumio volumio[1198]: info: VolumeController::SetAlsaVolume22 Jul 04 20:18:36 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:36 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:36 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:36 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:36 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:36 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:36 volumio volumio[1198]: info: VolumeController::SetAlsaVolume21 Jul 04 20:18:36 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:36 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:36 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:36 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:36 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:36 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:36 volumio volumio[1198]: info: VolumeController::SetAlsaVolume20 Jul 04 20:18:36 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:36 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:36 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:36 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:36 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:36 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:37 volumio volumio[1198]: info: VolumeController::SetAlsaVolume19 Jul 04 20:18:37 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:37 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:37 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:37 volumio volumio[1198]: info: VolumeController::SetAlsaVolume18 Jul 04 20:18:37 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:37 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:37 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:37 volumio volumio[1198]: info: VolumeController::SetAlsaVolume17 Jul 04 20:18:37 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:37 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:37 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:37 volumio volumio[1198]: info: VolumeController::SetAlsaVolume16 Jul 04 20:18:37 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:37 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:37 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:37 volumio volumio[1198]: info: VolumeController::SetAlsaVolume15 Jul 04 20:18:37 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:37 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:37 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:37 volumio volumio[1198]: info: VolumeController::SetAlsaVolume14 Jul 04 20:18:37 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:37 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:37 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:37 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:38 volumio volumio[1198]: info: VolumeController::SetAlsaVolume13 Jul 04 20:18:38 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:38 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:38 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:38 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:38 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:38 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:38 volumio volumio[1198]: info: VolumeController::SetAlsaVolume14 Jul 04 20:18:38 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:38 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:38 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:38 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:38 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:38 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:40 volumio volumio[1198]: info: CoreCommandRouter::volumioSeek Jul 04 20:18:40 volumio volumio[1198]: info: CoreStateMachine::seek Jul 04 20:18:40 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:40 volumio volumio[1198]: info: TRACKBLOCK {"id":"111809907:25037805@genre:pop","service":"yandex_music","type":"song","name":"Nasty Girl","title":"Nasty Girl","album":"Nasty Girl","artist":"DEKOVA","duration":263,"albumart":"https://avatars.yandex.net/get-music-content/8871869/795177e6.a.25037805-1/200x200","uri":"yandex_music/track/111809907:25037805@genre:pop","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Jul 04 20:18:40 volumio volumio[1198]: info: CoreStateMachine::startPlaybackTimer Jul 04 20:18:40 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:40 volumio volumio[1198]: info: ControllerMpd::seek Jul 04 20:18:40 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:40 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:40 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:40 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:40 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:40 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:40 volumio volumio[1198]: error: null Jul 04 20:18:40 volumio volumio[1198]: info: Jul 04 20:18:40 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:40 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:40 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:40 volumio volumio[1198]: info: Jul 04 20:18:40 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:40 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:40 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:40 volumio volumio[1198]: info: Jul 04 20:18:40 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:40 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:40 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:40 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:18:40 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:18:40 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:18:40 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:40 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:40 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:40 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:40 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:40 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:40 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:40 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 04 20:18:40 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:40 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:40 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:40 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:40 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:40 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:40 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":139856,"duration":263,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nasty Girl","artist":"DEKOVA","album":"Nasty Girl","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5a57a72aab12a78ca6959d56fffd9135bc0dd564f987a83dab4a31df94e8486c%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68756804%2F41011%2F6230260%2F9dbde181.167718050.7.111809907%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:40 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:40 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:40 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:40 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:40 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:40 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:40 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:40 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:40 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:40 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:40 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:40 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":139856,"duration":263,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nasty Girl","artist":"DEKOVA","album":"Nasty Girl","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5a57a72aab12a78ca6959d56fffd9135bc0dd564f987a83dab4a31df94e8486c%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68756804%2F41011%2F6230260%2F9dbde181.167718050.7.111809907%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:40 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:40 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:40 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:40 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:40 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:40 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:40 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:40 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:40 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:40 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:40 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:40 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":139856,"duration":263,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nasty Girl","artist":"DEKOVA","album":"Nasty Girl","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5a57a72aab12a78ca6959d56fffd9135bc0dd564f987a83dab4a31df94e8486c%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68756804%2F41011%2F6230260%2F9dbde181.167718050.7.111809907%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:40 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:40 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:40 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:40 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:40 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:40 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:40 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:40 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:41 volumio volumio[1198]: info: ------------------------------ 23ms Jul 04 20:18:41 volumio volumio[1198]: info: ------------------------------ 23ms Jul 04 20:18:41 volumio volumio[1198]: info: ------------------------------ 22ms Jul 04 20:18:41 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:41 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:41 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:41 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:41 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:41 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:41 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:41 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:41 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:41 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:41 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:41 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:41 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:41 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:41 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:41 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:41 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:41 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:42 volumio kernel: mmc0: running CQE recovery Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::volumioSeek Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::seek Jul 04 20:18:44 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:44 volumio volumio[1198]: info: TRACKBLOCK {"id":"111809907:25037805@genre:pop","service":"yandex_music","type":"song","name":"Nasty Girl","title":"Nasty Girl","album":"Nasty Girl","artist":"DEKOVA","duration":263,"albumart":"https://avatars.yandex.net/get-music-content/8871869/795177e6.a.25037805-1/200x200","uri":"yandex_music/track/111809907:25037805@genre:pop","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::startPlaybackTimer Jul 04 20:18:44 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:44 volumio volumio[1198]: info: ControllerMpd::seek Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:44 volumio volumio[1198]: error: null Jul 04 20:18:44 volumio volumio[1198]: info: Jul 04 20:18:44 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:44 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:44 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:44 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:44 volumio volumio[1198]: info: Jul 04 20:18:44 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:44 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:44 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:44 volumio volumio[1198]: info: Jul 04 20:18:44 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:44 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:44 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:44 volumio kernel: mmc0: running CQE recovery Jul 04 20:18:44 volumio volumio[1198]: info: sendMpdCommand status took 5 milliseconds Jul 04 20:18:44 volumio volumio[1198]: info: sendMpdCommand status took 2 milliseconds Jul 04 20:18:44 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:18:44 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:44 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:44 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:44 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:44 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:44 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:44 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:44 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:44 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:44 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:44 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:44 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:44 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:44 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":160000,"duration":263,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nasty Girl","artist":"DEKOVA","album":"Nasty Girl","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5a57a72aab12a78ca6959d56fffd9135bc0dd564f987a83dab4a31df94e8486c%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68756804%2F41011%2F6230260%2F9dbde181.167718050.7.111809907%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:44 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:44 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:44 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:44 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:44 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":160857,"duration":263,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nasty Girl","artist":"DEKOVA","album":"Nasty Girl","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5a57a72aab12a78ca6959d56fffd9135bc0dd564f987a83dab4a31df94e8486c%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68756804%2F41011%2F6230260%2F9dbde181.167718050.7.111809907%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:44 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:44 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:44 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:44 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:44 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":160857,"duration":263,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nasty Girl","artist":"DEKOVA","album":"Nasty Girl","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5a57a72aab12a78ca6959d56fffd9135bc0dd564f987a83dab4a31df94e8486c%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68756804%2F41011%2F6230260%2F9dbde181.167718050.7.111809907%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:44 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:44 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:44 volumio volumio[1198]: info: ------------------------------ 29ms Jul 04 20:18:44 volumio volumio[1198]: info: ------------------------------ 26ms Jul 04 20:18:44 volumio volumio[1198]: info: ------------------------------ 25ms Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:44 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:44 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:44 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:44 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:44 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:44 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:44 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:44 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::volumioSeek Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::seek Jul 04 20:18:45 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:45 volumio volumio[1198]: info: TRACKBLOCK {"id":"111809907:25037805@genre:pop","service":"yandex_music","type":"song","name":"Nasty Girl","title":"Nasty Girl","album":"Nasty Girl","artist":"DEKOVA","duration":263,"albumart":"https://avatars.yandex.net/get-music-content/8871869/795177e6.a.25037805-1/200x200","uri":"yandex_music/track/111809907:25037805@genre:pop","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::startPlaybackTimer Jul 04 20:18:45 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:45 volumio volumio[1198]: info: ControllerMpd::seek Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:45 volumio volumio[1198]: error: null Jul 04 20:18:45 volumio volumio[1198]: info: Jul 04 20:18:45 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:45 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:45 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:45 volumio volumio[1198]: info: Jul 04 20:18:45 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:45 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:45 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:45 volumio volumio[1198]: info: Jul 04 20:18:45 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:45 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:45 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:45 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:45 volumio volumio[1198]: info: sendMpdCommand status took 4 milliseconds Jul 04 20:18:45 volumio volumio[1198]: info: sendMpdCommand status took 3 milliseconds Jul 04 20:18:45 volumio volumio[1198]: info: sendMpdCommand status took 3 milliseconds Jul 04 20:18:45 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:45 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:45 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:45 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:45 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:45 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:45 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:45 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:45 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 04 20:18:45 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:45 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:45 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:45 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:45 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:45 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":164000,"duration":263,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nasty Girl","artist":"DEKOVA","album":"Nasty Girl","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5a57a72aab12a78ca6959d56fffd9135bc0dd564f987a83dab4a31df94e8486c%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68756804%2F41011%2F6230260%2F9dbde181.167718050.7.111809907%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:45 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:45 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:45 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:45 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:45 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":164000,"duration":263,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nasty Girl","artist":"DEKOVA","album":"Nasty Girl","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5a57a72aab12a78ca6959d56fffd9135bc0dd564f987a83dab4a31df94e8486c%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68756804%2F41011%2F6230260%2F9dbde181.167718050.7.111809907%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:45 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:45 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:45 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:45 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:45 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":164000,"duration":263,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nasty Girl","artist":"DEKOVA","album":"Nasty Girl","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5a57a72aab12a78ca6959d56fffd9135bc0dd564f987a83dab4a31df94e8486c%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68756804%2F41011%2F6230260%2F9dbde181.167718050.7.111809907%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:45 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:45 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:45 volumio volumio[1198]: info: ------------------------------ 24ms Jul 04 20:18:45 volumio volumio[1198]: info: ------------------------------ 23ms Jul 04 20:18:45 volumio volumio[1198]: info: ------------------------------ 24ms Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:45 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:45 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:45 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:45 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:45 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:45 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:45 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:45 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:49 volumio kernel: mmc0: running CQE recovery Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioSeek Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::seek Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:51 volumio volumio[1198]: info: TRACKBLOCK {"id":"111809907:25037805@genre:pop","service":"yandex_music","type":"song","name":"Nasty Girl","title":"Nasty Girl","album":"Nasty Girl","artist":"DEKOVA","duration":263,"albumart":"https://avatars.yandex.net/get-music-content/8871869/795177e6.a.25037805-1/200x200","uri":"yandex_music/track/111809907:25037805@genre:pop","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::startPlaybackTimer Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:51 volumio volumio[1198]: info: ControllerMpd::seek Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:51 volumio volumio[1198]: error: null Jul 04 20:18:51 volumio volumio[1198]: info: Jul 04 20:18:51 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:51 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:51 volumio volumio[1198]: info: Jul 04 20:18:51 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:51 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:51 volumio volumio[1198]: info: Jul 04 20:18:51 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:51 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:51 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:18:51 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:51 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:18:51 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:51 volumio kernel: mmc0: running CQE recovery Jul 04 20:18:51 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:51 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:51 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":259000,"duration":263,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nasty Girl","artist":"DEKOVA","album":"Nasty Girl","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5a57a72aab12a78ca6959d56fffd9135bc0dd564f987a83dab4a31df94e8486c%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68756804%2F41011%2F6230260%2F9dbde181.167718050.7.111809907%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:51 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:51 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:51 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:51 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":259000,"duration":263,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nasty Girl","artist":"DEKOVA","album":"Nasty Girl","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5a57a72aab12a78ca6959d56fffd9135bc0dd564f987a83dab4a31df94e8486c%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68756804%2F41011%2F6230260%2F9dbde181.167718050.7.111809907%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:51 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:51 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:51 volumio volumio[1198]: info: ------------------------------ 14ms Jul 04 20:18:51 volumio volumio[1198]: info: ------------------------------ 14ms Jul 04 20:18:51 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:51 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:51 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":259000,"duration":263,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nasty Girl","artist":"DEKOVA","album":"Nasty Girl","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5a57a72aab12a78ca6959d56fffd9135bc0dd564f987a83dab4a31df94e8486c%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68756804%2F41011%2F6230260%2F9dbde181.167718050.7.111809907%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:51 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:51 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:51 volumio volumio[1198]: info: ------------------------------ 22ms Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:51 volumio volumio[1198]: info: Prefetching next song Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3" Jul 04 20:18:51 volumio volumio[1198]: info: Jul 04 20:18:51 volumio volumio[1198]: ---------------------------- MPD announces system playlist update Jul 04 20:18:51 volumio volumio[1198]: info: Ignoring MPD Status Update Jul 04 20:18:51 volumio volumio[1198]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3" took 1 milliseconds Jul 04 20:18:51 volumio volumio[1198]: verbose: MPD COMMAND [object Object] Jul 04 20:18:51 volumio volumio[1198]: verbose: MPD COMMAND [object Object] Jul 04 20:18:51 volumio volumio[1198]: verbose: MPD COMMAND [object Object] Jul 04 20:18:51 volumio volumio[1198]: info: Jul 04 20:18:51 volumio volumio[1198]: ---------------------------- MPD announces system playlist update Jul 04 20:18:51 volumio volumio[1198]: info: Ignoring MPD Status Update Jul 04 20:18:51 volumio volumio[1198]: info: Jul 04 20:18:51 volumio volumio[1198]: ---------------------------- MPD announces system playlist update Jul 04 20:18:51 volumio volumio[1198]: info: Ignoring MPD Status Update Jul 04 20:18:51 volumio volumio[1198]: info: Jul 04 20:18:51 volumio volumio[1198]: ---------------------------- MPD announces system playlist update Jul 04 20:18:51 volumio volumio[1198]: info: Ignoring MPD Status Update Jul 04 20:18:51 volumio volumio[1198]: info: ------------------------------ 1ms Jul 04 20:18:51 volumio volumio[1198]: info: ------------------------------ 1ms Jul 04 20:18:51 volumio volumio[1198]: info: ------------------------------ 0ms Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 04 20:18:51 volumio volumio[1198]: info: Jul 04 20:18:51 volumio volumio[1198]: ---------------------------- MPD announces system playlist update Jul 04 20:18:51 volumio volumio[1198]: info: Ignoring MPD Status Update Jul 04 20:18:51 volumio volumio[1198]: info: Jul 04 20:18:51 volumio volumio[1198]: ---------------------------- MPD announces state update: options Jul 04 20:18:51 volumio volumio[1198]: info: ------------------------------ 1ms Jul 04 20:18:51 volumio volumio[1198]: info: sendMpdCommand consume 1 took 0 milliseconds Jul 04 20:18:51 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:51 volumio volumio[1198]: info: Jul 04 20:18:51 volumio volumio[1198]: ---------------------------- MPD announces system playlist update Jul 04 20:18:51 volumio volumio[1198]: info: Ignoring MPD Status Update Jul 04 20:18:51 volumio volumio[1198]: info: ------------------------------ 4ms Jul 04 20:18:51 volumio volumio[1198]: info: sendMpdCommand status took 4 milliseconds Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:51 volumio volumio[1198]: info: Jul 04 20:18:51 volumio volumio[1198]: ---------------------------- MPD announces state update: options Jul 04 20:18:51 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:51 volumio volumio[1198]: info: ------------------------------ 1ms Jul 04 20:18:51 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:51 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:51 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":260737,"duration":263,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nasty Girl","artist":"DEKOVA","album":"Nasty Girl","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5a57a72aab12a78ca6959d56fffd9135bc0dd564f987a83dab4a31df94e8486c%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68756804%2F41011%2F6230260%2F9dbde181.167718050.7.111809907%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:51 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:51 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:51 volumio volumio[1198]: info: ------------------------------ 14ms Jul 04 20:18:51 volumio volumio[1198]: info: Jul 04 20:18:51 volumio volumio[1198]: ---------------------------- MPD announces state update: options Jul 04 20:18:51 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:51 volumio volumio[1198]: info: sendMpdCommand status took 10 milliseconds Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: sendMpdCommand status took 7 milliseconds Jul 04 20:18:51 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:51 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:51 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":260737,"duration":263,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nasty Girl","artist":"DEKOVA","album":"Nasty Girl","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5a57a72aab12a78ca6959d56fffd9135bc0dd564f987a83dab4a31df94e8486c%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68756804%2F41011%2F6230260%2F9dbde181.167718050.7.111809907%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:51 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:51 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:51 volumio volumio[1198]: info: ------------------------------ 24ms Jul 04 20:18:51 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 04 20:18:51 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:51 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:51 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":260737,"duration":263,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nasty Girl","artist":"DEKOVA","album":"Nasty Girl","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5a57a72aab12a78ca6959d56fffd9135bc0dd564f987a83dab4a31df94e8486c%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68756804%2F41011%2F6230260%2F9dbde181.167718050.7.111809907%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:51 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:51 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:51 volumio volumio[1198]: info: ------------------------------ 21ms Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreCommandRouter::volumioGetQueue Jul 04 20:18:51 volumio volumio[1198]: info: CoreStateMachine::getQueue Jul 04 20:18:51 volumio volumio[1198]: info: CorePlayQueue::getQueue Jul 04 20:18:53 volumio volumio[1198]: info: CoreCommandRouter::volumioAddQueueItems Jul 04 20:18:53 volumio volumio[1198]: info: CoreStateMachine::addQueueItems Jul 04 20:18:53 volumio volumio[1198]: info: CorePlayQueue::addQueueItems Jul 04 20:18:53 volumio volumio[1198]: info: Preload queue cleared Jul 04 20:18:53 volumio volumio[1198]: info: Adding Item to queue: yandex_music/track/138657617:36412477@genre:pop Jul 04 20:18:53 volumio volumio[1198]: info: Exploding uri yandex_music/track/138657617:36412477@genre:pop in service yandex_music Jul 04 20:18:53 volumio volumio[1198]: info: CoreCommandRouter::volumioAddQueueItems Jul 04 20:18:53 volumio volumio[1198]: info: CoreStateMachine::addQueueItems Jul 04 20:18:53 volumio volumio[1198]: info: CorePlayQueue::addQueueItems Jul 04 20:18:53 volumio volumio[1198]: info: Preload queue cleared Jul 04 20:18:53 volumio volumio[1198]: info: Adding Item to queue: yandex_music/track/122337436:29681100@genre:pop Jul 04 20:18:53 volumio volumio[1198]: info: Exploding uri yandex_music/track/122337436:29681100@genre:pop in service yandex_music Jul 04 20:18:53 volumio volumio[1198]: info: CoreCommandRouter::volumioPushQueue Jul 04 20:18:53 volumio volumio[1198]: info: CorePlayQueue::saveQueue Jul 04 20:18:53 volumio volumio[1198]: info: CoreCommandRouter::volumioPushQueue Jul 04 20:18:53 volumio volumio[1198]: info: CorePlayQueue::saveQueue Jul 04 20:18:53 volumio volumio[1198]: info: CoreStateMachine::updateTrackBlock Jul 04 20:18:53 volumio volumio[1198]: info: CorePlayQueue::getTrackBlock Jul 04 20:18:53 volumio volumio[1198]: info: CoreStateMachine::updateTrackBlock Jul 04 20:18:53 volumio volumio[1198]: info: CorePlayQueue::getTrackBlock Jul 04 20:18:53 volumio volumio[1198]: info: Jul 04 20:18:53 volumio volumio[1198]: ---------------------------- MPD announces system playlist update Jul 04 20:18:53 volumio volumio[1198]: info: Ignoring MPD Status Update Jul 04 20:18:53 volumio volumio[1198]: info: Jul 04 20:18:53 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:53 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:53 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:53 volumio volumio[1198]: info: Jul 04 20:18:53 volumio volumio[1198]: ---------------------------- MPD announces system playlist update Jul 04 20:18:53 volumio volumio[1198]: info: Ignoring MPD Status Update Jul 04 20:18:53 volumio volumio[1198]: info: Jul 04 20:18:53 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:53 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:53 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:53 volumio volumio[1198]: info: Jul 04 20:18:53 volumio volumio[1198]: ---------------------------- MPD announces system playlist update Jul 04 20:18:53 volumio volumio[1198]: info: Ignoring MPD Status Update Jul 04 20:18:53 volumio volumio[1198]: info: Jul 04 20:18:53 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:53 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:53 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:53 volumio volumio[1198]: info: ------------------------------ 2ms Jul 04 20:18:53 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:18:53 volumio volumio[1198]: info: ------------------------------ 1ms Jul 04 20:18:53 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:18:53 volumio volumio[1198]: info: ------------------------------ 1ms Jul 04 20:18:53 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:18:53 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:53 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:53 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:53 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:53 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:53 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:53 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:53 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:53 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:53 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:53 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:53 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:53 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:53 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:53 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:53 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Loneliness","artist":"Purple Disco Machine","album":"Exotica","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:53 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:53 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:53 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:53 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:53 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:53 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:53 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:53 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:53 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:53 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:53 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:53 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:53 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Loneliness","artist":"Purple Disco Machine","album":"Exotica","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:53 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:53 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:53 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:53 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:53 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:53 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:53 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:53 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:53 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:53 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:53 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:18:53 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Loneliness","artist":"Purple Disco Machine","album":"Exotica","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:53 volumio volumio[1198]: verbose: CURRENT POSITION 0 Jul 04 20:18:53 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:53 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:53 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:53 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:53 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:53 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:53 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:53 volumio volumio[1198]: info: ------------------------------ 20ms Jul 04 20:18:53 volumio volumio[1198]: info: ------------------------------ 20ms Jul 04 20:18:53 volumio volumio[1198]: info: ------------------------------ 20ms Jul 04 20:18:54 volumio volumio[1198]: info: CoreStateMachine::startPlaybackTimer Jul 04 20:18:54 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:54 volumio volumio[1198]: info: Jul 04 20:18:54 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:54 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:54 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:54 volumio volumio[1198]: info: Jul 04 20:18:54 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:54 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:54 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:54 volumio volumio[1198]: info: Jul 04 20:18:54 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:54 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:54 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:54 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:18:54 volumio volumio[1198]: info: sendMpdCommand status took 0 milliseconds Jul 04 20:18:54 volumio volumio[1198]: info: sendMpdCommand status took 0 milliseconds Jul 04 20:18:54 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:54 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:54 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:54 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:54 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:54 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:54 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 04 20:18:54 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 04 20:18:54 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 04 20:18:54 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:54 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:54 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:54 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:54 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:54 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:54 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":320,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Loneliness","artist":"Purple Disco Machine","album":"Exotica","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:54 volumio volumio[1198]: verbose: CURRENT POSITION 1 Jul 04 20:18:54 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:54 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:54 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:54 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:54 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:54 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:54 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:54 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:54 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:54 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:54 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:54 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:54 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":170,"duration":320,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Loneliness","artist":"Purple Disco Machine","album":"Exotica","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:54 volumio volumio[1198]: verbose: CURRENT POSITION 1 Jul 04 20:18:54 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:54 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:54 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:54 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:54 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:54 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:54 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:54 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:54 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:54 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:54 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:54 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:54 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":217,"duration":320,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Loneliness","artist":"Purple Disco Machine","album":"Exotica","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:54 volumio volumio[1198]: verbose: CURRENT POSITION 1 Jul 04 20:18:54 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:54 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:54 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:54 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:54 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:54 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:54 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:54 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:54 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:54 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:54 volumio volumio[1198]: info: ------------------------------ 22ms Jul 04 20:18:54 volumio volumio[1198]: info: ------------------------------ 23ms Jul 04 20:18:54 volumio volumio[1198]: info: ------------------------------ 22ms Jul 04 20:18:55 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:55 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:55 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:55 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:56 volumio kernel: mmc0: running CQE recovery Jul 04 20:18:57 volumio volumio[1198]: info: CoreCommandRouter::volumioSeek Jul 04 20:18:57 volumio volumio[1198]: info: CoreStateMachine::seek Jul 04 20:18:57 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:57 volumio volumio[1198]: info: TRACKBLOCK {"id":"89094976:19751007@genre:pop","service":"yandex_music","type":"song","name":"Loneliness","title":"Loneliness","album":"Exotica","artist":"Purple Disco Machine","duration":320,"albumart":"https://avatars.yandex.net/get-music-content/9868087/43531740.a.19751007-1/200x200","uri":"yandex_music/track/89094976:19751007@genre:pop","samplerate":"","bitdepth":"","trackType":"","channels":2} Jul 04 20:18:57 volumio volumio[1198]: info: CoreStateMachine::startPlaybackTimer Jul 04 20:18:57 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:57 volumio volumio[1198]: info: ControllerMpd::seek Jul 04 20:18:57 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:57 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:57 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:57 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:57 volumio volumio[1198]: error: null Jul 04 20:18:57 volumio volumio[1198]: info: Jul 04 20:18:57 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:57 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:57 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:57 volumio volumio[1198]: info: Jul 04 20:18:57 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:57 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:57 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:57 volumio volumio[1198]: info: Jul 04 20:18:57 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:57 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:57 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:57 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:18:57 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:18:57 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:18:57 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:57 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:57 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:57 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:57 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:57 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:57 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:57 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:57 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:57 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:57 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:57 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:57 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:57 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:57 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:57 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29000,"duration":320,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Loneliness","artist":"Purple Disco Machine","album":"Exotica","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:57 volumio volumio[1198]: verbose: CURRENT POSITION 1 Jul 04 20:18:57 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:57 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:57 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:57 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:57 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:57 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:57 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:57 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:57 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:57 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:57 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:57 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:57 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29000,"duration":320,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Loneliness","artist":"Purple Disco Machine","album":"Exotica","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:57 volumio volumio[1198]: verbose: CURRENT POSITION 1 Jul 04 20:18:57 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:57 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:57 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:57 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:57 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:57 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:57 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:57 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:57 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:57 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:57 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:57 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:57 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29000,"duration":320,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Loneliness","artist":"Purple Disco Machine","album":"Exotica","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:57 volumio volumio[1198]: verbose: CURRENT POSITION 1 Jul 04 20:18:57 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:57 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:57 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:57 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:57 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:57 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:57 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:57 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:57 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:57 volumio volumio[1198]: info: ------------------------------ 22ms Jul 04 20:18:57 volumio volumio[1198]: info: ------------------------------ 22ms Jul 04 20:18:57 volumio volumio[1198]: info: ------------------------------ 22ms Jul 04 20:18:58 volumio kernel: mmc0: running CQE recovery Jul 04 20:18:58 volumio volumio[1198]: info: CoreCommandRouter::volumioSeek Jul 04 20:18:58 volumio volumio[1198]: info: CoreStateMachine::seek Jul 04 20:18:58 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:58 volumio volumio[1198]: info: TRACKBLOCK {"id":"89094976:19751007@genre:pop","service":"yandex_music","type":"song","name":"Loneliness","title":"Loneliness","album":"Exotica","artist":"Purple Disco Machine","duration":320,"albumart":"https://avatars.yandex.net/get-music-content/9868087/43531740.a.19751007-1/200x200","uri":"yandex_music/track/89094976:19751007@genre:pop","samplerate":"","bitdepth":"","trackType":"","channels":2} Jul 04 20:18:58 volumio volumio[1198]: info: CoreStateMachine::startPlaybackTimer Jul 04 20:18:58 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:58 volumio volumio[1198]: info: ControllerMpd::seek Jul 04 20:18:58 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:58 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:58 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:18:58 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:59 volumio volumio[1198]: error: null Jul 04 20:18:59 volumio volumio[1198]: info: Jul 04 20:18:59 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:59 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:59 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:59 volumio volumio[1198]: info: Jul 04 20:18:59 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:59 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:59 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:59 volumio volumio[1198]: info: Jul 04 20:18:59 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:18:59 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:18:59 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:18:59 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:18:59 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:18:59 volumio volumio[1198]: info: sendMpdCommand status took 0 milliseconds Jul 04 20:18:59 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:59 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:59 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:59 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:59 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:18:59 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:18:59 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:59 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:59 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 04 20:18:59 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:59 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:59 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:18:59 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:59 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:59 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:59 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":54000,"duration":320,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Loneliness","artist":"Purple Disco Machine","album":"Exotica","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:59 volumio volumio[1198]: verbose: CURRENT POSITION 1 Jul 04 20:18:59 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:59 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:59 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:59 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:59 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:59 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:59 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:59 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:59 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:59 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:59 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:59 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:59 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":54000,"duration":320,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Loneliness","artist":"Purple Disco Machine","album":"Exotica","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:59 volumio volumio[1198]: verbose: CURRENT POSITION 1 Jul 04 20:18:59 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:59 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:59 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:59 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:59 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:59 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:59 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:59 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:59 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:59 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:18:59 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:18:59 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:59 volumio volumio[1198]: verbose: STATE SERVICE {"status":"play","position":0,"seek":54000,"duration":320,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Loneliness","artist":"Purple Disco Machine","album":"Exotica","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:18:59 volumio volumio[1198]: verbose: CURRENT POSITION 1 Jul 04 20:18:59 volumio volumio[1198]: info: CoreStateMachine::syncState stateService play Jul 04 20:18:59 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus play Jul 04 20:18:59 volumio volumio[1198]: info: Received an update from plugin. extracting info from payload Jul 04 20:18:59 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:59 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:59 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:59 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:18:59 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:18:59 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:18:59 volumio volumio[1198]: info: ------------------------------ 26ms Jul 04 20:19:00 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 20:19:00 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 20:19:00 volumio volumio[1198]: info: Discovery: Getting this device information Jul 04 20:19:00 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Jul 04 20:19:00 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:19:00 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 20:19:01 volumio volumio[1198]: info: VolumeController::SetAlsaVolume13 Jul 04 20:19:01 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:01 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:19:01 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:19:01 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:02 volumio volumio[1198]: info: VolumeController::SetAlsaVolume12 Jul 04 20:19:02 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:02 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:19:02 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:19:02 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:02 volumio volumio[1198]: info: VolumeController::SetAlsaVolume11 Jul 04 20:19:02 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:02 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:19:02 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:19:02 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:02 volumio volumio[1198]: info: VolumeController::SetAlsaVolume10 Jul 04 20:19:02 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:02 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:19:02 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:19:02 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:03 volumio kernel: mmc0: running CQE recovery Jul 04 20:19:03 volumio volumio[1198]: info: VolumeController::SetAlsaVolume11 Jul 04 20:19:03 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:03 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:19:03 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:19:03 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:04 volumio volumio[1198]: info: VolumeController::SetAlsaVolume12 Jul 04 20:19:04 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:04 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:19:04 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:19:04 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:04 volumio volumio[1198]: info: VolumeController::SetAlsaVolume13 Jul 04 20:19:04 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:04 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:19:04 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:19:04 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:04 volumio volumio[1198]: info: VolumeController::SetAlsaVolume14 Jul 04 20:19:04 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:04 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:19:04 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:19:04 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:05 volumio volumio[1198]: info: VolumeController::SetAlsaVolume15 Jul 04 20:19:05 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:05 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:19:05 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:19:05 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:08 volumio kernel: mmc0: running CQE recovery Jul 04 20:19:12 volumio kernel: mmc0: running CQE recovery Jul 04 20:19:12 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 04 20:19:12 volumio volumio[1198]: info: Preload queue cleared Jul 04 20:19:17 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 04 20:19:17 volumio kernel: mmc0: running CQE recovery Jul 04 20:19:17 volumio volumio[1198]: info: Preload queue cleared Jul 04 20:19:19 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 04 20:19:20 volumio volumio[1198]: info: Preload queue cleared Jul 04 20:19:23 volumio kernel: mmc0: running CQE recovery Jul 04 20:19:23 volumio kernel: mmc0: running CQE recovery Jul 04 20:19:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 04 20:19:23 volumio volumio[1198]: info: Preload queue cleared Jul 04 20:19:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 04 20:19:25 volumio volumio[1198]: info: Preload queue cleared Jul 04 20:19:28 volumio kernel: mmc0: running CQE recovery Jul 04 20:19:28 volumio kernel: mmc0: running CQE recovery Jul 04 20:19:28 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 04 20:19:29 volumio volumio[1198]: info: Preload queue cleared Jul 04 20:19:29 volumio volumio[1198]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12390 Jul 04 20:19:29 volumio volumio[1198]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12388 Jul 04 20:19:29 volumio volumio[1198]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12392 Jul 04 20:19:29 volumio volumio[1198]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12393 Jul 04 20:19:29 volumio volumio[1198]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12387 Jul 04 20:19:29 volumio volumio[1198]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12389 Jul 04 20:19:29 volumio volumio[1198]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12391 Jul 04 20:19:29 volumio volumio[1198]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12390 in service upnp_browser Jul 04 20:19:29 volumio volumio[1198]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12388 in service upnp_browser Jul 04 20:19:29 volumio volumio[1198]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12392 in service upnp_browser Jul 04 20:19:29 volumio volumio[1198]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12393 in service upnp_browser Jul 04 20:19:29 volumio volumio[1198]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12387 in service upnp_browser Jul 04 20:19:29 volumio volumio[1198]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12389 in service upnp_browser Jul 04 20:19:29 volumio volumio[1198]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12391 in service upnp_browser Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 20:19:30 volumio volumio[1198]: info: Discovery: Getting this device information Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Jul 04 20:19:30 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 20:19:30 volumio volumio[1198]: info: Preload queue cleared Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::ClearQueue Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::stop Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::stPlaybackTimer Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::updateTrackBlock Jul 04 20:19:30 volumio volumio[1198]: info: CorePlayQueue::getTrackBlock Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:30 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::serviceStop Jul 04 20:19:30 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::serviceStop Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 04 20:19:30 volumio volumio[1198]: info: ControllerMpd::stop Jul 04 20:19:30 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand stop Jul 04 20:19:30 volumio volumio[1198]: info: CorePlayQueue::clearPlayQueue Jul 04 20:19:30 volumio volumio[1198]: info: CorePlayQueue::saveQueue Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::volumioPushQueue Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::addQueueItems Jul 04 20:19:30 volumio volumio[1198]: info: CorePlayQueue::addQueueItems Jul 04 20:19:30 volumio volumio[1198]: info: Preload queue cleared Jul 04 20:19:30 volumio volumio[1198]: info: Adding Item to queue: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$1294 Jul 04 20:19:30 volumio volumio[1198]: info: Exploding uri upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$1294 in service upnp_browser Jul 04 20:19:30 volumio volumio[1198]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12390 Jul 04 20:19:30 volumio volumio[1198]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12390 Jul 04 20:19:30 volumio volumio[1198]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12388 Jul 04 20:19:30 volumio volumio[1198]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12388 Jul 04 20:19:30 volumio volumio[1198]: info: sendMpdCommand stop took 8 milliseconds Jul 04 20:19:30 volumio volumio[1198]: info: Jul 04 20:19:30 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:19:30 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:19:30 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:19:30 volumio volumio[1198]: info: Jul 04 20:19:30 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:19:30 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:19:30 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:19:30 volumio volumio[1198]: info: Jul 04 20:19:30 volumio volumio[1198]: ---------------------------- MPD announces state update: player Jul 04 20:19:30 volumio volumio[1198]: info: ControllerMpd::getState Jul 04 20:19:30 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Jul 04 20:19:30 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:19:30 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:19:30 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:19:30 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:19:30 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:19:30 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:19:30 volumio volumio[1198]: info: sendMpdCommand status took 1 milliseconds Jul 04 20:19:30 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 04 20:19:30 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 04 20:19:30 volumio volumio[1198]: verbose: ControllerMpd::parseState Jul 04 20:19:30 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 20:19:30 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:19:30 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:19:30 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:30 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:19:30 volumio volumio[1198]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Loneliness","artist":"Purple Disco Machine","album":"Exotica","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:19:30 volumio volumio[1198]: verbose: CURRENT POSITION 1 Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::syncState stateService stop Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus stop Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:30 volumio volumio[1198]: info: No code Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:30 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:30 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:19:30 volumio volumio[1198]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Loneliness","artist":"Purple Disco Machine","album":"Exotica","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:19:30 volumio volumio[1198]: verbose: CURRENT POSITION 1 Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::syncState stateService stop Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus stop Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:30 volumio volumio[1198]: info: No code Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:30 volumio volumio[1198]: info: ------------------------------ 20ms Jul 04 20:19:30 volumio volumio[1198]: info: ------------------------------ 19ms Jul 04 20:19:30 volumio volumio[1198]: info: sendMpdCommand playlistinfo took 19 milliseconds Jul 04 20:19:30 volumio volumio[1198]: verbose: ControllerMpd::parseTrackInfo Jul 04 20:19:30 volumio volumio[1198]: info: ControllerMpd::pushState Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:30 volumio volumio[1198]: info: CorePlayQueue::getTrack 1 Jul 04 20:19:30 volumio volumio[1198]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Loneliness","artist":"Purple Disco Machine","album":"Exotica","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-spbmiran-13.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2ee424d5d114996be57d1d312c3e8aa1747d4607f10b861f86de1ca4ec0470c0%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68750648%2F49759%2F32302%2F462ef51d.134177601.8.89094976%2F320.mp3&ext=.mp3","trackType":"mp3"} Jul 04 20:19:30 volumio volumio[1198]: verbose: CURRENT POSITION 1 Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::syncState stateService stop Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus stop Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:30 volumio volumio[1198]: info: No code Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::pushState Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Jul 04 20:19:30 volumio volumio[1198]: info: ------------------------------ 31ms Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::volumioPushQueue Jul 04 20:19:30 volumio volumio[1198]: info: CorePlayQueue::saveQueue Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::updateTrackBlock Jul 04 20:19:30 volumio volumio[1198]: info: CorePlayQueue::getTrackBlock Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::volumioPlay Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::play index 2 Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::addQueueItems Jul 04 20:19:30 volumio volumio[1198]: info: CorePlayQueue::addQueueItems Jul 04 20:19:30 volumio volumio[1198]: info: Preload queue cleared Jul 04 20:19:30 volumio volumio[1198]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12392 Jul 04 20:19:30 volumio volumio[1198]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12392 Jul 04 20:19:30 volumio volumio[1198]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12393 Jul 04 20:19:30 volumio volumio[1198]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12393 Jul 04 20:19:30 volumio volumio[1198]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12387 Jul 04 20:19:30 volumio volumio[1198]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12387 Jul 04 20:19:30 volumio volumio[1198]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12389 Jul 04 20:19:30 volumio volumio[1198]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12389 Jul 04 20:19:30 volumio volumio[1198]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12391 Jul 04 20:19:30 volumio volumio[1198]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12391 Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::stop Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 20:19:30 volumio volumio[1198]: info: CoreCommandRouter::volumioPushQueue Jul 04 20:19:30 volumio volumio[1198]: info: CorePlayQueue::saveQueue Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::play index undefined Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 20:19:30 volumio volumio[1198]: info: CoreStateMachine::updateTrackBlock Jul 04 20:19:30 volumio volumio[1198]: info: CorePlayQueue::getTrackBlock Jul 04 20:19:30 volumio volumio[1198]: info: CorePlayQueue::getTrack 2 Jul 04 20:19:34 volumio kernel: mmc0: running CQE recovery Jul 04 20:19:39 volumio kernel: mmc0: running CQE recovery Jul 04 20:19:42 volumio volumio[1198]: info: Preload queue cleared Jul 04 20:19:42 volumio volumio[1198]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 04 20:19:42 volumio volumio[1198]: info: CoreStateMachine::ClearQueue Jul 04 20:19:42 volumio volumio[1198]: info: CoreStateMachine::stop Jul 04 20:19:42 volumio volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 20:19:42 volumio volumio[1198]: info: CorePlayQueue::clearPlayQueue Jul 04 20:19:42 volumio volumio[1198]: info: CorePlayQueue::saveQueue Jul 04 20:19:42 volumio volumio[1198]: info: CoreCommandRouter::volumioPushQueue Jul 04 20:19:42 volumio volumio[1198]: info: CoreStateMachine::addQueueItems Jul 04 20:19:42 volumio volumio[1198]: info: CorePlayQueue::addQueueItems Jul 04 20:19:42 volumio volumio[1198]: info: Preload queue cleared Jul 04 20:19:42 volumio volumio[1198]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12388 Jul 04 20:19:42 volumio volumio[1198]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@12388 Jul 04 20:19:42 volumio volumio[1198]: info: CoreCommandRouter::volumioPushQueue Jul 04 20:19:42 volumio volumio[1198]: info: CorePlayQueue::saveQueue Jul 04 20:19:42 volumio volumio[1198]: info: CoreStateMachine::updateTrackBlock Jul 04 20:19:42 volumio volumio[1198]: info: CorePlayQueue::getTrackBlock Jul 04 20:19:42 volumio volumio[1198]: info: CoreCommandRouter::volumioPlay Jul 04 20:19:42 volumio volumio[1198]: info: CoreStateMachine::play index 0 Jul 04 20:19:42 volumio volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 20:19:42 volumio volumio[1198]: info: CoreStateMachine::stop Jul 04 20:19:42 volumio volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 20:19:42 volumio volumio[1198]: info: CoreStateMachine::play index undefined Jul 04 20:19:42 volumio volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 20:19:42 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Jul 04 20:19:43 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 04 20:19:43 volumio volumio[1198]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 20:19:43 volumio volumio[1198]: TypeError: Cannot read property 'length' of undefined Jul 04 20:19:43 volumio volumio[1198]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 04 20:19:43 volumio volumio[1198]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 04 20:19:43 volumio volumio[1198]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 04 20:19:43 volumio volumio[1198]: at Parser.emit (events.js:315:20) Jul 04 20:19:43 volumio volumio[1198]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 04 20:19:43 volumio volumio[1198]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 04 20:19:43 volumio volumio[1198]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 04 20:19:43 volumio volumio[1198]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 04 20:19:43 volumio volumio[1198]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 04 20:19:43 volumio volumio[1198]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 04 20:19:43 volumio volumio[1198]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 04 20:19:43 volumio volumio[1198]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 04 20:19:43 volumio volumio[1198]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 04 20:19:43 volumio volumio[1198]: at IncomingMessage.emit (events.js:327:22) Jul 04 20:19:43 volumio volumio[1198]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 04 20:19:43 volumio volumio[1198]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 04 20:19:43 volumio volumio[1198]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 20:19:43 volumio sudo[3494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-04 20:18 Jul 04 20:19:43 volumio sudo[3494]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"