2015-10-20 19:02:43,212 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetFileStatsVDSCommand] (org.ovirt.thread.pool-8-thread-30) [31087434] START, GetFileStatsVDSCommand( storagePoolId = c3715f80-6435-45b6-94c2-a9a2869cd059, ignoreFailoverLimit = false), log id: 1017da05
2015-10-20 19:02:43,234 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetFileStatsVDSCommand] (org.ovirt.thread.pool-8-thread-30) [31087434] FINISH, GetFileStatsVDSCommand, return: {WindowsServer2012R2_VL_x64.iso={status=0, ctime=1420410000.0, size=4548247552}, debian-7.6.0-amd64-netinst.iso={status=0, ctime=1411420000.0, size=232783872}, Windows2003.iso={status=0, ctime=1419970000.0, size=654682112}, ovirt-guest-tools-3.5_5.iso={status=0, ctime=1411590000.0, size=106803200}, debian-7.6.0-i386-netinst.iso={status=0, ctime=1411420000.0, size=290455552}}, log id: 1017da05
2015-10-20 19:02:43,271 INFO  [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (org.ovirt.thread.pool-8-thread-30) [31087434] Finished automatic refresh process for ISO file type with success, for storage domain id 2cee08e0-e675-480a-903c-61f5fe244841.
2015-10-20 19:58:19,485 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-72) [4da69936] Attempting to update VMs/Templates Ovf.
2015-10-20 19:58:19,486 INFO  [org.ovirt.engine.core.bll.ProcessOvfUpdateForStoragePoolCommand] (DefaultQuartzScheduler_Worker-72) [2c1a843b] Running command: ProcessOvfUpdateForStoragePoolCommand internal: true. Entities affected :  ID: c3715f80-6435-45b6-94c2-a9a2869cd059 Type: StoragePool
2015-10-20 19:58:19,512 INFO  [org.ovirt.engine.core.bll.ProcessOvfUpdateForStoragePoolCommand] (DefaultQuartzScheduler_Worker-72) [2c1a843b] Lock freed to object EngineLock [exclusiveLocks= key: c3715f80-6435-45b6-94c2-a9a2869cd059 value: OVF_UPDATE
, sharedLocks= ]
2015-10-20 20:02:43,272 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetFileStatsVDSCommand] (org.ovirt.thread.pool-8-thread-9) [7e7d579d] START, GetFileStatsVDSCommand( storagePoolId = c3715f80-6435-45b6-94c2-a9a2869cd059, ignoreFailoverLimit = false), log id: 1fea7d4c
2015-10-20 20:02:43,288 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetFileStatsVDSCommand] (org.ovirt.thread.pool-8-thread-9) [7e7d579d] FINISH, GetFileStatsVDSCommand, return: {WindowsServer2012R2_VL_x64.iso={status=0, ctime=1420410000.0, size=4548247552}, debian-7.6.0-amd64-netinst.iso={status=0, ctime=1411420000.0, size=232783872}, Windows2003.iso={status=0, ctime=1419970000.0, size=654682112}, ovirt-guest-tools-3.5_5.iso={status=0, ctime=1411590000.0, size=106803200}, debian-7.6.0-i386-netinst.iso={status=0, ctime=1411420000.0, size=290455552}}, log id: 1fea7d4c
2015-10-20 20:02:43,347 INFO  [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (org.ovirt.thread.pool-8-thread-9) [7e7d579d] Finished automatic refresh process for ISO file type with success, for storage domain id 2cee08e0-e675-480a-903c-61f5fe244841.
2015-10-20 20:24:38,672 ERROR [org.ovirt.engine.core.utils.servlet.ServletUtils] (ajp--127.0.0.1-8702-4) Can't read file "/usr/share/ovirt-engine/files/spice/SpiceVersion.txt" for request "/ovirt-engine/services/files/spice/SpiceVersion.txt", will send a 404 error response.
2015-10-20 20:24:50,204 INFO  [org.ovirt.engine.core.bll.aaa.LoginAdminUserCommand] (ajp--127.0.0.1-8702-5) Running command: LoginAdminUserCommand internal: false.
2015-10-20 20:24:50,349 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-5) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin@internal logged in.
2015-10-20 20:35:45,682 ERROR [org.ovirt.engine.core.aaa.filters.BasicAuthenticationFilter] (ajp--127.0.0.1-8702-6) User admin authentication failed. profile is internal. Invocation Result code is 0. Authn result code is CREDENTIALS_INVALID
2015-10-20 20:35:56,832 INFO  [org.ovirt.engine.core.bll.aaa.LoginUserCommand] (ajp--127.0.0.1-8702-2) Running command: LoginUserCommand internal: false.
2015-10-20 20:35:56,844 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-2) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin@internal logged in.
2015-10-20 20:36:17,972 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetFileStatsVDSCommand] (ajp--127.0.0.1-8702-4) START, GetFileStatsVDSCommand( storagePoolId = c3715f80-6435-45b6-94c2-a9a2869cd059, ignoreFailoverLimit = false), log id: 21d8d229
2015-10-20 20:36:17,989 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetFileStatsVDSCommand] (ajp--127.0.0.1-8702-4) FINISH, GetFileStatsVDSCommand, return: {debian-8.2.0-amd64-netinst.iso={status=0, ctime=1445370000.0, size=258998272}, WindowsServer2012R2_VL_x64.iso={status=0, ctime=1420410000.0, size=4548247552}, debian-7.6.0-i386-netinst.iso={status=0, ctime=1411420000.0, size=290455552}, debian-7.6.0-amd64-netinst.iso={status=0, ctime=1411420000.0, size=232783872}, Windows2003.iso={status=0, ctime=1419970000.0, size=654682112}, ovirt-guest-tools-3.5_5.iso={status=0, ctime=1411590000.0, size=106803200}}, log id: 21d8d229
2015-10-20 20:36:18,001 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetFileStatsVDSCommand] (ajp--127.0.0.1-8702-4) START, GetFileStatsVDSCommand( storagePoolId = c3715f80-6435-45b6-94c2-a9a2869cd059, ignoreFailoverLimit = false), log id: 2b553593
2015-10-20 20:36:18,010 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetFileStatsVDSCommand] (ajp--127.0.0.1-8702-4) FINISH, GetFileStatsVDSCommand, return: {}, log id: 2b553593
2015-10-20 20:36:18,017 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Refresh image list succeeded for domain(s): Tilburg_ISO (All file type)
2015-10-20 20:55:10,335 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp--127.0.0.1-8702-4) [8347bdb] Lock Acquired to object EngineLock [exclusiveLocks= key: build-arm-mcu value: VM_NAME
, sharedLocks= ]
2015-10-20 20:55:10,388 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp--127.0.0.1-8702-4) [8347bdb] Running command: AddVmFromScratchCommand internal: false. Entities affected :  ID: bbac9c33-a85e-4a89-a817-1eba756d06ca Type: VdsGroupsAction group CREATE_VM with role type USER,  ID: bbac9c33-a85e-4a89-a817-1eba756d06ca Type: VdsGroupsAction group EDIT_ADMIN_VM_PROPERTIES with role type ADMIN
2015-10-20 20:55:10,764 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) [8347bdb] Correlation ID: 8347bdb, Job ID: 0be92432-cde4-4862-89c0-1c6f5abee3c0, Call Stack: null, Custom Event ID: -1, Message: VM build-arm-mcu was created by admin@internal.
2015-10-20 20:55:10,797 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp--127.0.0.1-8702-4) [8347bdb] Lock freed to object EngineLock [exclusiveLocks= key: build-arm-mcu value: VM_NAME
, sharedLocks= ]
2015-10-20 20:55:11,805 INFO  [org.ovirt.engine.core.bll.network.vm.AddVmInterfaceCommand] (ajp--127.0.0.1-8702-7) [74e4687e] Running command: AddVmInterfaceCommand internal: false. Entities affected :  ID: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d Type: VMAction group CONFIGURE_VM_NETWORK with role type USER,  ID: 81f04ef1-e1af-4502-a0d5-eb361ec54724 Type: VnicProfileAction group CONFIGURE_VM_NETWORK with role type USER
2015-10-20 20:55:11,959 INFO  [org.ovirt.engine.core.bll.network.vm.ActivateDeactivateVmNicCommand] (ajp--127.0.0.1-8702-7) [41c5dd19] Running command: ActivateDeactivateVmNicCommand internal: true. Entities affected :  ID: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d Type: VM
2015-10-20 20:55:11,980 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) [41c5dd19] Correlation ID: 41c5dd19, Call Stack: null, Custom Event ID: -1, Message: Network Interface nic1 (VirtIO) was plugged to VM build-arm-mcu. (User: admin@internal)
2015-10-20 20:55:11,997 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) [41c5dd19] Correlation ID: 74e4687e, Call Stack: null, Custom Event ID: -1, Message: Interface nic1 (VirtIO) was added to VM build-arm-mcu. (User: admin@internal)
2015-10-20 20:55:12,186 INFO  [org.ovirt.engine.core.bll.network.vm.ReorderVmNicsCommand] (ajp--127.0.0.1-8702-3) [6c54a68c] Running command: ReorderVmNicsCommand internal: false. Entities affected :  ID: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d Type: VMAction group CREATE_VM with role type USER
2015-10-20 20:55:43,314 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (ajp--127.0.0.1-8702-10) [247bb146] Lock Acquired to object EngineLock [exclusiveLocks= key: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d value: VM_DISK_BOOT
, sharedLocks= key: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d value: VM
]
2015-10-20 20:55:43,336 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (ajp--127.0.0.1-8702-10) [247bb146] Running command: AddDiskCommand internal: false. Entities affected :  ID: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d Type: VMAction group CONFIGURE_VM_STORAGE with role type USER,  ID: 8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b Type: StorageAction group CREATE_DISK with role type USER
2015-10-20 20:55:43,502 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (ajp--127.0.0.1-8702-10) [39ddc357] Running command: AddImageFromScratchCommand internal: true. Entities affected :  ID: 8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b Type: Storage
2015-10-20 20:55:43,628 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (ajp--127.0.0.1-8702-10) [39ddc357] Lock freed to object EngineLock [exclusiveLocks= key: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d value: VM_DISK_BOOT
, sharedLocks= key: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d value: VM
]
2015-10-20 20:55:43,629 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp--127.0.0.1-8702-10) [39ddc357] START, CreateImageVDSCommand( storagePoolId = c3715f80-6435-45b6-94c2-a9a2869cd059, ignoreFailoverLimit = false, storageDomainId = 8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b, imageGroupId = 370d8ba3-d982-4a86-967f-2602b8df1aa0, imageSizeInBytes = 10737418240, volumeFormat = RAW, newImageId = a271d353-35b7-4dd2-b722-e2fc3b117a8d, newImageDescription = {"DiskAlias":"build-arm-mcu_Disk1","DiskDescription":""}), log id: 370c6329
2015-10-20 20:55:43,630 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp--127.0.0.1-8702-10) [39ddc357] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID
2015-10-20 20:55:43,674 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp--127.0.0.1-8702-10) [39ddc357] FINISH, CreateImageVDSCommand, return: a271d353-35b7-4dd2-b722-e2fc3b117a8d, log id: 370c6329
2015-10-20 20:55:43,703 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (ajp--127.0.0.1-8702-10) [39ddc357] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 80bb30fd-2c9c-4467-8d40-f95bbe482daf
2015-10-20 20:55:43,726 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp--127.0.0.1-8702-10) [39ddc357] CommandMultiAsyncTasks::AttachTask: Attaching task aaf1237e-509b-4b11-bc23-3a391e6c2bf5 to command 80bb30fd-2c9c-4467-8d40-f95bbe482daf.
2015-10-20 20:55:43,961 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (ajp--127.0.0.1-8702-10) [39ddc357] Adding task aaf1237e-509b-4b11-bc23-3a391e6c2bf5 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
2015-10-20 20:55:44,245 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-10) [39ddc357] Correlation ID: 247bb146, Job ID: ca8c2c11-7a80-458e-ba00-187d475d8fea, Call Stack: null, Custom Event ID: -1, Message: Add-Disk operation of build-arm-mcu_Disk1 was initiated on VM build-arm-mcu by admin@internal.
2015-10-20 20:55:44,245 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (ajp--127.0.0.1-8702-10) [39ddc357] BaseAsyncTask::startPollingTask: Starting to poll task aaf1237e-509b-4b11-bc23-3a391e6c2bf5.
2015-10-20 20:55:50,605 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-84) [321942e3] Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
2015-10-20 20:55:50,609 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-84) [321942e3] SPMAsyncTask::PollTask: Polling task aaf1237e-509b-4b11-bc23-3a391e6c2bf5 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
2015-10-20 20:55:50,717 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-84) [321942e3] BaseAsyncTask::onTaskEndSuccess: Task aaf1237e-509b-4b11-bc23-3a391e6c2bf5 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
2015-10-20 20:55:50,717 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-84) [321942e3] CommandAsyncTask::endActionIfNecessary: All tasks of command 80bb30fd-2c9c-4467-8d40-f95bbe482daf has ended -> executing endAction
2015-10-20 20:55:50,718 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-84) [321942e3] CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: 80bb30fd-2c9c-4467-8d40-f95bbe482daf): calling endAction .
2015-10-20 20:55:50,718 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-43) [321942e3] CommandAsyncTask::endCommandAction [within thread] context: Attempting to endAction AddDisk, executionIndex: 0
2015-10-20 20:55:50,796 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (org.ovirt.thread.pool-8-thread-43) [247bb146] Ending command successfully: org.ovirt.engine.core.bll.AddDiskCommand
2015-10-20 20:55:50,808 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (org.ovirt.thread.pool-8-thread-43) [39ddc357] Ending command successfully: org.ovirt.engine.core.bll.AddImageFromScratchCommand
2015-10-20 20:55:50,815 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-43) [39ddc357] START, GetImageInfoVDSCommand( storagePoolId = c3715f80-6435-45b6-94c2-a9a2869cd059, ignoreFailoverLimit = false, storageDomainId = 8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b, imageGroupId = 370d8ba3-d982-4a86-967f-2602b8df1aa0, imageId = a271d353-35b7-4dd2-b722-e2fc3b117a8d), log id: 58a4818f
2015-10-20 20:55:50,829 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-43) [39ddc357] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@82bf23f7, log id: 58a4818f
2015-10-20 20:55:50,858 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-43) Correlation ID: 247bb146, Job ID: ca8c2c11-7a80-458e-ba00-187d475d8fea, Call Stack: null, Custom Event ID: -1, Message: The disk build-arm-mcu_Disk1 was successfully added to VM build-arm-mcu.
2015-10-20 20:55:50,859 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-43) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk completed, handling the result.
2015-10-20 20:55:50,860 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-43) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk succeeded, clearing tasks.
2015-10-20 20:55:50,871 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-43) SPMAsyncTask::ClearAsyncTask: Attempting to clear task aaf1237e-509b-4b11-bc23-3a391e6c2bf5
2015-10-20 20:55:50,871 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-43) START, SPMClearTaskVDSCommand( storagePoolId = c3715f80-6435-45b6-94c2-a9a2869cd059, ignoreFailoverLimit = false, taskId = aaf1237e-509b-4b11-bc23-3a391e6c2bf5), log id: 4e4ccebf
2015-10-20 20:55:50,872 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-43) START, HSMClearTaskVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, taskId=aaf1237e-509b-4b11-bc23-3a391e6c2bf5), log id: 149985c2
2015-10-20 20:55:50,916 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-43) FINISH, HSMClearTaskVDSCommand, log id: 149985c2
2015-10-20 20:55:50,917 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-43) FINISH, SPMClearTaskVDSCommand, log id: 4e4ccebf
2015-10-20 20:55:50,925 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-43) BaseAsyncTask::removeTaskFromDB: Removed task aaf1237e-509b-4b11-bc23-3a391e6c2bf5 from DataBase
2015-10-20 20:55:50,925 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-43) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 80bb30fd-2c9c-4467-8d40-f95bbe482daf
2015-10-20 20:56:17,480 INFO  [org.ovirt.engine.core.bll.RunVmOnceCommand] (ajp--127.0.0.1-8702-4) [372b0c47] Lock Acquired to object EngineLock [exclusiveLocks= key: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d value: VM
, sharedLocks= ]
2015-10-20 20:56:17,528 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-4) START, IsVmDuringInitiatingVDSCommand( vmId = a3d5dc65-ca9d-4d16-899f-7368b9f2c19d), log id: 2d3f5bc6
2015-10-20 20:56:17,528 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-4) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 2d3f5bc6
2015-10-20 20:56:17,574 INFO  [org.ovirt.engine.core.bll.RunVmOnceCommand] (ajp--127.0.0.1-8702-4) Running command: RunVmOnceCommand internal: false. Entities affected :  ID: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d Type: VMAction group VM_BASIC_OPERATIONS with role type USER
2015-10-20 20:56:17,650 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-4) Running VM with attached cd debian-8.2.0-amd64-netinst.iso
2015-10-20 20:56:17,651 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (ajp--127.0.0.1-8702-4) START, UpdateVmDynamicDataVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, vmDynamic=org.ovirt.engine.core.common.businessentities.VmDynamic@3e3fd028), log id: 5f0b5c46
2015-10-20 20:56:17,658 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (ajp--127.0.0.1-8702-4) FINISH, UpdateVmDynamicDataVDSCommand, log id: 5f0b5c46
2015-10-20 20:56:17,658 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (ajp--127.0.0.1-8702-4) START, IsoPrefixVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, storagePoolId=c3715f80-6435-45b6-94c2-a9a2869cd059), log id: 749b57e5
2015-10-20 20:56:17,659 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (ajp--127.0.0.1-8702-4) FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/esther.joris2k.nl:_mnt_data_iso/2cee08e0-e675-480a-903c-61f5fe244841/images/11111111-1111-1111-1111-111111111111, log id: 749b57e5
2015-10-20 20:56:17,663 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (ajp--127.0.0.1-8702-4) START, CreateVmVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, vmId=a3d5dc65-ca9d-4d16-899f-7368b9f2c19d, vm=VM [build-arm-mcu]), log id: 1f8854b
2015-10-20 20:56:17,664 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp--127.0.0.1-8702-4) START, CreateVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, vmId=a3d5dc65-ca9d-4d16-899f-7368b9f2c19d, vm=VM [build-arm-mcu]), log id: 3cb8d5f6
2015-10-20 20:56:17,675 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp--127.0.0.1-8702-4) org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand kvmEnable=true,keyboardLayout=en-us,nice=0,pitReinjection=false,displayNetwork=ovirtmgmt,copyPasteEnable=true,timeOffset=0,transparentHugePages=true,vmId=a3d5dc65-ca9d-4d16-899f-7368b9f2c19d,acpiEnable=true,custom={},spiceSslCipherSuite=DEFAULT,memSize=1024,smp=2,emulatedMachine=rhel6.5.0,vmType=kvm,memGuaranteedSize=512,display=qxl,smartcardEnable=false,bootMenuEnable=false,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,smpCoresPerSocket=1,maxVCpus=16,devices=[{specParams={ram=65536, vram=32768, heads=1}, device=qxl, type=video, deviceId=14367428-076b-410a-990e-196fb9904914}, {shared=false, bootOrder=1, iface=ide, index=2, specParams={}, path=/rhev/data-center/mnt/esther.joris2k.nl:_mnt_data_iso/2cee08e0-e675-480a-903c-61f5fe244841/images/11111111-1111-1111-1111-111111111111/debian-8.2.0-amd64-netinst.iso, device=cdrom, type=disk, readonly=true, deviceId=fb8b230b-b0f4-4c27-b198-605c0f1b1f0b}, {shared=false, index=0, volumeID=a271d353-35b7-4dd2-b722-e2fc3b117a8d, propagateErrors=off, format=raw, type=disk, iface=virtio, bootOrder=2, domainID=8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b, imageID=370d8ba3-d982-4a86-967f-2602b8df1aa0, specParams={}, optional=false, device=disk, poolID=c3715f80-6435-45b6-94c2-a9a2869cd059, readonly=false, deviceId=370d8ba3-d982-4a86-967f-2602b8df1aa0}, {bootOrder=3, nicModel=pv, specParams={outbound={}, inbound={}}, macAddr=00:1a:4a:69:c2:16, device=bridge, linkActive=true, type=interface, filter=vdsm-no-mac-spoofing, network=server2, deviceId=3d892877-430a-4d64-8619-41e9bd834b02}, {specParams={model=virtio}, device=memballoon, type=balloon, deviceId=23b3be19-5da3-4b53-a3a9-630bec239aa9}, {index=0, model=virtio-scsi, specParams={}, device=scsi, type=controller, deviceId=5bd979ff-8e2d-4585-a9f4-7f9b39614521}, {specParams={}, device=virtio-serial, type=controller, deviceId=47826a86-77f2-471c-bd06-f115dc1e259a}],vmName=build-arm-mcu,launchPaused=true,cpuType=Haswell,fileTransferEnable=true
2015-10-20 20:56:17,694 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp--127.0.0.1-8702-4) FINISH, CreateVDSCommand, log id: 3cb8d5f6
2015-10-20 20:56:17,749 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (ajp--127.0.0.1-8702-4) FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 1f8854b
2015-10-20 20:56:17,750 INFO  [org.ovirt.engine.core.bll.RunVmOnceCommand] (ajp--127.0.0.1-8702-4) Lock freed to object EngineLock [exclusiveLocks= key: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d value: VM
, sharedLocks= ]
2015-10-20 20:56:17,826 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) Correlation ID: 372b0c47, Job ID: 76985adb-4408-4c2b-ab8d-56f8e450d90b, Call Stack: null, Custom Event ID: -1, Message: Starting in paused mode VM build-arm-mcu was initiated by admin@internal.
2015-10-20 20:56:19,879 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-83) [7023177a] START, DestroyVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, vmId=a3d5dc65-ca9d-4d16-899f-7368b9f2c19d, force=false, secondsToWait=0, gracefully=false, reason=), log id: 3536d484
2015-10-20 20:56:19,899 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-83) [7023177a] FINISH, DestroyVDSCommand, log id: 3536d484
2015-10-20 20:56:19,912 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-83) [7023177a] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VM build-arm-mcu is down with error. Exit message: internal error unsupported flags (0x1) in function virLockManagerSanlockNew.
2015-10-20 20:56:19,913 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-83) [7023177a] Running on vds during rerun failed vm: null
2015-10-20 20:56:19,931 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-83) [7023177a] VM build-arm-mcu (a3d5dc65-ca9d-4d16-899f-7368b9f2c19d) is running in db and not running in VDS esther
2015-10-20 20:56:20,039 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-83) [7023177a] Rerun vm a3d5dc65-ca9d-4d16-899f-7368b9f2c19d. Called from vds esther
2015-10-20 20:56:20,121 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-38) [7023177a] Correlation ID: 372b0c47, Job ID: 76985adb-4408-4c2b-ab8d-56f8e450d90b, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM build-arm-mcu on Host esther.
2015-10-20 20:56:20,215 INFO  [org.ovirt.engine.core.bll.RunVmOnceCommand] (org.ovirt.thread.pool-8-thread-38) [7023177a] Lock Acquired to object EngineLock [exclusiveLocks= key: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d value: VM
, sharedLocks= ]
2015-10-20 20:56:20,223 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-8-thread-38) START, IsVmDuringInitiatingVDSCommand( vmId = a3d5dc65-ca9d-4d16-899f-7368b9f2c19d), log id: 42e4914f
2015-10-20 20:56:20,223 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-8-thread-38) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 42e4914f
2015-10-20 20:56:20,227 WARN  [org.ovirt.engine.core.bll.RunVmOnceCommand] (org.ovirt.thread.pool-8-thread-38) CanDoAction of action RunVmOnce failed for user admin@internal. Reasons: VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,SCHEDULING_NO_HOSTS
2015-10-20 20:56:20,227 INFO  [org.ovirt.engine.core.bll.RunVmOnceCommand] (org.ovirt.thread.pool-8-thread-38) Lock freed to object EngineLock [exclusiveLocks= key: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d value: VM
, sharedLocks= ]
2015-10-20 20:56:20,358 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-38) Correlation ID: 372b0c47, Job ID: 76985adb-4408-4c2b-ab8d-56f8e450d90b, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM build-arm-mcu (User: admin@internal).
2015-10-20 20:56:20,361 INFO  [org.ovirt.engine.core.bll.ProcessDownVmCommand] (org.ovirt.thread.pool-8-thread-27) [1a87390a] Running command: ProcessDownVmCommand internal: true.
2015-10-20 20:56:51,359 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-75) [6de20c4d] Setting new tasks map. The map contains now 0 tasks
2015-10-20 20:56:51,360 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-75) [6de20c4d] Cleared all tasks of pool c3715f80-6435-45b6-94c2-a9a2869cd059.
2015-10-20 20:57:10,152 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-6) [542ba583] Lock Acquired to object EngineLock [exclusiveLocks= key: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d value: VM
, sharedLocks= ]
2015-10-20 20:57:10,173 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-6) [542ba583] START, IsVmDuringInitiatingVDSCommand( vmId = a3d5dc65-ca9d-4d16-899f-7368b9f2c19d), log id: 89913a4
2015-10-20 20:57:10,173 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-6) [542ba583] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 89913a4
2015-10-20 20:57:10,216 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-21) [542ba583] Running command: RunVmCommand internal: false. Entities affected :  ID: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d Type: VMAction group VM_BASIC_OPERATIONS with role type USER
2015-10-20 20:57:10,234 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-21) [542ba583] START, UpdateVmDynamicDataVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, vmDynamic=org.ovirt.engine.core.common.businessentities.VmDynamic@80b68e35), log id: 6ec03d0c
2015-10-20 20:57:10,241 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-21) [542ba583] FINISH, UpdateVmDynamicDataVDSCommand, log id: 6ec03d0c
2015-10-20 20:57:10,245 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-8-thread-21) [542ba583] START, CreateVmVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, vmId=a3d5dc65-ca9d-4d16-899f-7368b9f2c19d, vm=VM [build-arm-mcu]), log id: 45b4b1fe
2015-10-20 20:57:10,248 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-21) [542ba583] START, CreateVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, vmId=a3d5dc65-ca9d-4d16-899f-7368b9f2c19d, vm=VM [build-arm-mcu]), log id: 2400d0bc
2015-10-20 20:57:10,260 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-21) [542ba583] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand kvmEnable=true,keyboardLayout=en-us,nice=0,pitReinjection=false,displayNetwork=ovirtmgmt,copyPasteEnable=true,timeOffset=0,transparentHugePages=true,vmId=a3d5dc65-ca9d-4d16-899f-7368b9f2c19d,acpiEnable=true,custom={},spiceSslCipherSuite=DEFAULT,memSize=1024,smp=2,emulatedMachine=rhel6.5.0,vmType=kvm,memGuaranteedSize=512,display=qxl,smartcardEnable=false,bootMenuEnable=false,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,smpCoresPerSocket=1,maxVCpus=16,devices=[{specParams={ram=65536, vram=32768, heads=1}, device=qxl, type=video, deviceId=14367428-076b-410a-990e-196fb9904914}, {shared=false, iface=ide, index=2, specParams={path=}, path=, device=cdrom, type=disk, readonly=true, deviceId=60965a40-f384-4a67-9aad-6a74e2364ada}, {shared=false, index=0, volumeID=a271d353-35b7-4dd2-b722-e2fc3b117a8d, propagateErrors=off, format=raw, type=disk, iface=virtio, bootOrder=1, domainID=8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b, imageID=370d8ba3-d982-4a86-967f-2602b8df1aa0, specParams={}, optional=false, device=disk, poolID=c3715f80-6435-45b6-94c2-a9a2869cd059, readonly=false, deviceId=370d8ba3-d982-4a86-967f-2602b8df1aa0}, {nicModel=pv, specParams={outbound={}, inbound={}}, macAddr=00:1a:4a:69:c2:16, device=bridge, linkActive=true, type=interface, filter=vdsm-no-mac-spoofing, network=server2, deviceId=3d892877-430a-4d64-8619-41e9bd834b02}, {specParams={model=virtio}, device=memballoon, type=balloon, deviceId=23b3be19-5da3-4b53-a3a9-630bec239aa9}, {index=0, model=virtio-scsi, specParams={}, device=scsi, type=controller, deviceId=5bd979ff-8e2d-4585-a9f4-7f9b39614521}, {specParams={}, device=virtio-serial, type=controller, deviceId=47826a86-77f2-471c-bd06-f115dc1e259a}],vmName=build-arm-mcu,cpuType=Haswell,fileTransferEnable=true
2015-10-20 20:57:10,279 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-21) [542ba583] FINISH, CreateVDSCommand, log id: 2400d0bc
2015-10-20 20:57:10,296 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-8-thread-21) [542ba583] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 45b4b1fe
2015-10-20 20:57:10,297 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-21) [542ba583] Lock freed to object EngineLock [exclusiveLocks= key: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d value: VM
, sharedLocks= ]
2015-10-20 20:57:10,342 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-21) [542ba583] Correlation ID: 542ba583, Job ID: 3b470650-2035-41bc-8275-8afacc37f52f, Call Stack: null, Custom Event ID: -1, Message: VM build-arm-mcu was started by admin@internal (Host: esther).
2015-10-20 20:57:10,520 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-8) [5c8d6135] START, FullListVdsCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, vds=Host[esther,7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c], vmIds=[a3d5dc65-ca9d-4d16-899f-7368b9f2c19d]), log id: 46ca292c
2015-10-20 20:57:10,523 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-8) [5c8d6135] FINISH, FullListVdsCommand, return: [{acpiEnable=true, emulatedMachine=rhel6.5.0, vmId=a3d5dc65-ca9d-4d16-899f-7368b9f2c19d, memGuaranteedSize=512, transparentHugePages=true, displaySecurePort=-1, spiceSslCipherSuite=DEFAULT, cpuType=Haswell, smp=2, smartcardEnable=false, custom={}, vmType=kvm, memSize=1024, smpCoresPerSocket=1, vmName=build-arm-mcu, nice=0, status=WaitForLaunch, bootMenuEnable=false, pid=0, copyPasteEnable=true, displayIp=192.168.10.33, displayPort=-1, guestDiskMapping={}, clientIp=, fileTransferEnable=true, nicModel=rtl8139,pv, keyboardLayout=en-us, kvmEnable=true, pitReinjection=false, displayNetwork=ovirtmgmt, devices=[Ljava.lang.Object;@2552c03, timeOffset=0, maxVCpus=16, spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard, display=qxl}], log id: 46ca292c
2015-10-20 20:57:10,525 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-8) [5c8d6135] Received a memballoon Device without an address when processing VM a3d5dc65-ca9d-4d16-899f-7368b9f2c19d devices, skipping device: {target=1048576, specParams={model=virtio}, deviceType=balloon, deviceId=23b3be19-5da3-4b53-a3a9-630bec239aa9, device=memballoon, type=balloon}
2015-10-20 20:57:10,525 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-8) [5c8d6135] Received a scsi Device without an address when processing VM a3d5dc65-ca9d-4d16-899f-7368b9f2c19d devices, skipping device: {index=0, specParams={}, deviceType=controller, deviceId=5bd979ff-8e2d-4585-a9f4-7f9b39614521, device=scsi, model=virtio-scsi, type=controller}
2015-10-20 20:57:10,526 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-8) [5c8d6135] Received a virtio-serial Device without an address when processing VM a3d5dc65-ca9d-4d16-899f-7368b9f2c19d devices, skipping device: {device=virtio-serial, specParams={}, deviceType=controller, type=controller, deviceId=47826a86-77f2-471c-bd06-f115dc1e259a}
2015-10-20 20:57:10,526 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-8) [5c8d6135] Received a qxl Device without an address when processing VM a3d5dc65-ca9d-4d16-899f-7368b9f2c19d devices, skipping device: {device=qxl, specParams={vram=32768, ram=65536, heads=1}, deviceType=video, type=video, deviceId=14367428-076b-410a-990e-196fb9904914}
2015-10-20 20:57:10,527 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-8) [5c8d6135] Received a spice Device without an address when processing VM a3d5dc65-ca9d-4d16-899f-7368b9f2c19d devices, skipping device: {device=spice, specParams={displayNetwork=ovirtmgmt, spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard, keyMap=en-us, displayIp=192.168.10.33, copyPasteEnable=true}, deviceType=graphics, type=graphics}
2015-10-20 20:57:10,527 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-8) [5c8d6135] Received a bridge Device without an address when processing VM a3d5dc65-ca9d-4d16-899f-7368b9f2c19d devices, skipping device: {nicModel=pv, macAddr=00:1a:4a:69:c2:16, linkActive=true, network=server2, filter=vdsm-no-mac-spoofing, specParams={inbound={}, outbound={}}, deviceType=interface, deviceId=3d892877-430a-4d64-8619-41e9bd834b02, device=bridge, type=interface}
2015-10-20 20:57:10,528 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-8) [5c8d6135] Received a cdrom Device without an address when processing VM a3d5dc65-ca9d-4d16-899f-7368b9f2c19d devices, skipping device: {index=2, iface=ide, specParams={path=}, readonly=true, deviceType=disk, deviceId=60965a40-f384-4a67-9aad-6a74e2364ada, path=, device=cdrom, shared=false, type=disk}
2015-10-20 20:57:10,529 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-8) [5c8d6135] Received a disk Device without an address when processing VM a3d5dc65-ca9d-4d16-899f-7368b9f2c19d devices, skipping device: {volumeInfo={domainID=8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b, volType=path, leaseOffset=0, volumeID=a271d353-35b7-4dd2-b722-e2fc3b117a8d, leasePath=/rhev/data-center/mnt/_mnt_data_storage/8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b/images/370d8ba3-d982-4a86-967f-2602b8df1aa0/a271d353-35b7-4dd2-b722-e2fc3b117a8d.lease, imageID=370d8ba3-d982-4a86-967f-2602b8df1aa0, path=/rhev/data-center/mnt/_mnt_data_storage/8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b/images/370d8ba3-d982-4a86-967f-2602b8df1aa0/a271d353-35b7-4dd2-b722-e2fc3b117a8d}, index=0, iface=virtio, apparentsize=10737418240, imageID=370d8ba3-d982-4a86-967f-2602b8df1aa0, readonly=false, deviceType=disk, shared=false, truesize=0, type=disk, domainID=8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b, reqsize=0, format=raw, deviceId=370d8ba3-d982-4a86-967f-2602b8df1aa0, poolID=c3715f80-6435-45b6-94c2-a9a2869cd059, device=disk, path=/rhev/data-center/c3715f80-6435-45b6-94c2-a9a2869cd059/8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b/images/370d8ba3-d982-4a86-967f-2602b8df1aa0/a271d353-35b7-4dd2-b722-e2fc3b117a8d, propagateErrors=off, optional=false, bootOrder=1, volumeID=a271d353-35b7-4dd2-b722-e2fc3b117a8d, specParams={}, volumeChain=[Ljava.lang.Object;@2d3a4118}
2015-10-20 20:57:13,635 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-78) [c0e8d28] START, DestroyVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, vmId=a3d5dc65-ca9d-4d16-899f-7368b9f2c19d, force=false, secondsToWait=0, gracefully=false, reason=), log id: 11d38c30
2015-10-20 20:57:13,645 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-78) [c0e8d28] FINISH, DestroyVDSCommand, log id: 11d38c30
2015-10-20 20:57:13,708 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-78) [c0e8d28] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VM build-arm-mcu is down with error. Exit message: internal error unsupported flags (0x1) in function virLockManagerSanlockNew.
2015-10-20 20:57:13,709 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-78) [c0e8d28] Running on vds during rerun failed vm: null
2015-10-20 20:57:13,710 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-78) [c0e8d28] VM build-arm-mcu (a3d5dc65-ca9d-4d16-899f-7368b9f2c19d) is running in db and not running in VDS esther
2015-10-20 20:57:13,710 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-78) [c0e8d28] START, FullListVdsCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, vds=Host[esther,7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c], vmIds=[a3d5dc65-ca9d-4d16-899f-7368b9f2c19d]), log id: 3ff165c
2015-10-20 20:57:13,713 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-78) [c0e8d28] FINISH, FullListVdsCommand, return: [], log id: 3ff165c
2015-10-20 20:57:13,742 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-78) [c0e8d28] Rerun vm a3d5dc65-ca9d-4d16-899f-7368b9f2c19d. Called from vds esther
2015-10-20 20:57:13,767 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-15) [c0e8d28] Correlation ID: 542ba583, Job ID: 3b470650-2035-41bc-8275-8afacc37f52f, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM build-arm-mcu on Host esther.
2015-10-20 20:57:13,784 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-15) [c0e8d28] Lock Acquired to object EngineLock [exclusiveLocks= key: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d value: VM
, sharedLocks= ]
2015-10-20 20:57:13,790 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-8-thread-15) [c0e8d28] START, IsVmDuringInitiatingVDSCommand( vmId = a3d5dc65-ca9d-4d16-899f-7368b9f2c19d), log id: 6c1f91f3
2015-10-20 20:57:13,791 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-8-thread-15) [c0e8d28] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 6c1f91f3
2015-10-20 20:57:13,800 WARN  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-15) [c0e8d28] CanDoAction of action RunVm failed for user admin@internal. Reasons: VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,SCHEDULING_NO_HOSTS
2015-10-20 20:57:13,801 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-15) [c0e8d28] Lock freed to object EngineLock [exclusiveLocks= key: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d value: VM
, sharedLocks= ]
2015-10-20 20:57:13,850 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-15) [c0e8d28] Correlation ID: 542ba583, Job ID: 3b470650-2035-41bc-8275-8afacc37f52f, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM build-arm-mcu (User: admin@internal).
2015-10-20 20:57:13,853 INFO  [org.ovirt.engine.core.bll.ProcessDownVmCommand] (org.ovirt.thread.pool-8-thread-28) [288515ee] Running command: ProcessDownVmCommand internal: true.
2015-10-20 20:57:39,776 INFO  [org.ovirt.engine.core.bll.RunVmOnceCommand] (ajp--127.0.0.1-8702-2) [4e595d94] Lock Acquired to object EngineLock [exclusiveLocks= key: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d value: VM
, sharedLocks= ]
2015-10-20 20:57:39,795 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-2) START, IsVmDuringInitiatingVDSCommand( vmId = a3d5dc65-ca9d-4d16-899f-7368b9f2c19d), log id: 66840cec
2015-10-20 20:57:39,795 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-2) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 66840cec
2015-10-20 20:57:39,822 INFO  [org.ovirt.engine.core.bll.RunVmOnceCommand] (ajp--127.0.0.1-8702-2) Running command: RunVmOnceCommand internal: false. Entities affected :  ID: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d Type: VMAction group VM_BASIC_OPERATIONS with role type USER
2015-10-20 20:57:39,850 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-2) Running VM with attached cd debian-8.2.0-amd64-netinst.iso
2015-10-20 20:57:39,851 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (ajp--127.0.0.1-8702-2) START, UpdateVmDynamicDataVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, vmDynamic=org.ovirt.engine.core.common.businessentities.VmDynamic@8716e21a), log id: 7623739b
2015-10-20 20:57:39,859 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (ajp--127.0.0.1-8702-2) FINISH, UpdateVmDynamicDataVDSCommand, log id: 7623739b
2015-10-20 20:57:39,859 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (ajp--127.0.0.1-8702-2) START, IsoPrefixVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, storagePoolId=c3715f80-6435-45b6-94c2-a9a2869cd059), log id: 419f4f6c
2015-10-20 20:57:39,860 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (ajp--127.0.0.1-8702-2) FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/esther.joris2k.nl:_mnt_data_iso/2cee08e0-e675-480a-903c-61f5fe244841/images/11111111-1111-1111-1111-111111111111, log id: 419f4f6c
2015-10-20 20:57:39,862 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (ajp--127.0.0.1-8702-2) START, CreateVmVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, vmId=a3d5dc65-ca9d-4d16-899f-7368b9f2c19d, vm=VM [build-arm-mcu]), log id: 6b6a4af8
2015-10-20 20:57:39,865 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp--127.0.0.1-8702-2) START, CreateVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, vmId=a3d5dc65-ca9d-4d16-899f-7368b9f2c19d, vm=VM [build-arm-mcu]), log id: 2b6ae217
2015-10-20 20:57:39,879 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp--127.0.0.1-8702-2) org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand kvmEnable=true,keyboardLayout=en-us,nice=0,pitReinjection=false,displayNetwork=ovirtmgmt,copyPasteEnable=true,timeOffset=0,transparentHugePages=true,vmId=a3d5dc65-ca9d-4d16-899f-7368b9f2c19d,acpiEnable=true,custom={},spiceSslCipherSuite=DEFAULT,memSize=1024,smp=2,emulatedMachine=rhel6.5.0,vmType=kvm,memGuaranteedSize=512,display=qxl,smartcardEnable=false,bootMenuEnable=false,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,smpCoresPerSocket=1,maxVCpus=16,devices=[{specParams={ram=65536, vram=32768, heads=1}, device=qxl, type=video, deviceId=14367428-076b-410a-990e-196fb9904914}, {shared=false, bootOrder=2, iface=ide, index=2, specParams={}, path=/rhev/data-center/mnt/esther.joris2k.nl:_mnt_data_iso/2cee08e0-e675-480a-903c-61f5fe244841/images/11111111-1111-1111-1111-111111111111/debian-8.2.0-amd64-netinst.iso, device=cdrom, type=disk, readonly=true, deviceId=5f7a21b3-6bf4-43a3-acaa-a122be81dc72}, {shared=false, index=0, volumeID=a271d353-35b7-4dd2-b722-e2fc3b117a8d, propagateErrors=off, format=raw, type=disk, iface=virtio, bootOrder=1, domainID=8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b, imageID=370d8ba3-d982-4a86-967f-2602b8df1aa0, specParams={}, optional=false, device=disk, poolID=c3715f80-6435-45b6-94c2-a9a2869cd059, readonly=false, deviceId=370d8ba3-d982-4a86-967f-2602b8df1aa0}, {bootOrder=3, nicModel=pv, specParams={outbound={}, inbound={}}, macAddr=00:1a:4a:69:c2:16, device=bridge, linkActive=true, type=interface, filter=vdsm-no-mac-spoofing, network=server2, deviceId=3d892877-430a-4d64-8619-41e9bd834b02}, {specParams={model=virtio}, device=memballoon, type=balloon, deviceId=23b3be19-5da3-4b53-a3a9-630bec239aa9}, {index=0, model=virtio-scsi, specParams={}, device=scsi, type=controller, deviceId=5bd979ff-8e2d-4585-a9f4-7f9b39614521}, {specParams={}, device=virtio-serial, type=controller, deviceId=47826a86-77f2-471c-bd06-f115dc1e259a}],vmName=build-arm-mcu,cpuType=Haswell,fileTransferEnable=true
2015-10-20 20:57:39,896 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp--127.0.0.1-8702-2) FINISH, CreateVDSCommand, log id: 2b6ae217
2015-10-20 20:57:39,913 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (ajp--127.0.0.1-8702-2) FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 6b6a4af8
2015-10-20 20:57:39,914 INFO  [org.ovirt.engine.core.bll.RunVmOnceCommand] (ajp--127.0.0.1-8702-2) Lock freed to object EngineLock [exclusiveLocks= key: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d value: VM
, sharedLocks= ]
2015-10-20 20:57:39,930 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-2) Correlation ID: 4e595d94, Job ID: 6d1d68f2-5700-44dc-901f-ee62ac006f66, Call Stack: null, Custom Event ID: -1, Message: VM build-arm-mcu was started by admin@internal (Host: esther).
2015-10-20 20:57:42,393 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-35) [2cc47e00] START, DestroyVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, vmId=a3d5dc65-ca9d-4d16-899f-7368b9f2c19d, force=false, secondsToWait=0, gracefully=false, reason=), log id: 413b11b7
2015-10-20 20:57:42,409 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-35) [2cc47e00] FINISH, DestroyVDSCommand, log id: 413b11b7
2015-10-20 20:57:42,472 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-35) [2cc47e00] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VM build-arm-mcu is down with error. Exit message: internal error unsupported flags (0x1) in function virLockManagerSanlockNew.
2015-10-20 20:57:42,473 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-35) [2cc47e00] Running on vds during rerun failed vm: null
2015-10-20 20:57:42,474 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-35) [2cc47e00] VM build-arm-mcu (a3d5dc65-ca9d-4d16-899f-7368b9f2c19d) is running in db and not running in VDS esther
2015-10-20 20:57:42,772 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-35) [2cc47e00] Rerun vm a3d5dc65-ca9d-4d16-899f-7368b9f2c19d. Called from vds esther
2015-10-20 20:57:42,797 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-16) [2cc47e00] Correlation ID: 4e595d94, Job ID: 6d1d68f2-5700-44dc-901f-ee62ac006f66, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM build-arm-mcu on Host esther.
2015-10-20 20:57:42,814 INFO  [org.ovirt.engine.core.bll.RunVmOnceCommand] (org.ovirt.thread.pool-8-thread-16) [2cc47e00] Lock Acquired to object EngineLock [exclusiveLocks= key: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d value: VM
, sharedLocks= ]
2015-10-20 20:57:42,821 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-8-thread-16) START, IsVmDuringInitiatingVDSCommand( vmId = a3d5dc65-ca9d-4d16-899f-7368b9f2c19d), log id: c9da80b
2015-10-20 20:57:42,822 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-8-thread-16) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: c9da80b
2015-10-20 20:57:42,824 WARN  [org.ovirt.engine.core.bll.RunVmOnceCommand] (org.ovirt.thread.pool-8-thread-16) CanDoAction of action RunVmOnce failed for user admin@internal. Reasons: VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,SCHEDULING_NO_HOSTS
2015-10-20 20:57:42,824 INFO  [org.ovirt.engine.core.bll.RunVmOnceCommand] (org.ovirt.thread.pool-8-thread-16) Lock freed to object EngineLock [exclusiveLocks= key: a3d5dc65-ca9d-4d16-899f-7368b9f2c19d value: VM
, sharedLocks= ]
2015-10-20 20:57:42,884 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-16) Correlation ID: 4e595d94, Job ID: 6d1d68f2-5700-44dc-901f-ee62ac006f66, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM build-arm-mcu (User: admin@internal).
2015-10-20 20:57:42,887 INFO  [org.ovirt.engine.core.bll.ProcessDownVmCommand] (org.ovirt.thread.pool-8-thread-37) [2b15bdd5] Running command: ProcessDownVmCommand internal: true.
2015-10-20 20:58:19,513 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-37) [75d4b7d8] Attempting to update VMs/Templates Ovf.
2015-10-20 20:58:19,514 INFO  [org.ovirt.engine.core.bll.ProcessOvfUpdateForStoragePoolCommand] (DefaultQuartzScheduler_Worker-37) [1b19a511] Running command: ProcessOvfUpdateForStoragePoolCommand internal: true. Entities affected :  ID: c3715f80-6435-45b6-94c2-a9a2869cd059 Type: StoragePool
2015-10-20 20:58:19,970 INFO  [org.ovirt.engine.core.bll.ProcessOvfUpdateForStoragePoolCommand] (DefaultQuartzScheduler_Worker-37) [1b19a511] Lock freed to object EngineLock [exclusiveLocks= key: c3715f80-6435-45b6-94c2-a9a2869cd059 value: OVF_UPDATE
, sharedLocks= ]
2015-10-20 20:58:19,981 INFO  [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (DefaultQuartzScheduler_Worker-37) [ec60c56] Lock Acquired to object EngineLock [exclusiveLocks= key: 8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b value: STORAGE
key: 1a58df14-7211-48b3-896c-2d3b260e301a value: DISK
key: 9245c20e-e8f1-4c4d-955e-187bdb48d76d value: DISK
, sharedLocks= key: c3715f80-6435-45b6-94c2-a9a2869cd059 value: OVF_UPDATE
]
2015-10-20 20:58:19,982 INFO  [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (DefaultQuartzScheduler_Worker-37) [ec60c56] Running command: ProcessOvfUpdateForStorageDomainCommand internal: true. Entities affected :  ID: 8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b Type: Storage
2015-10-20 20:58:20,085 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-37) [ec60c56] START, SetVolumeDescriptionVDSCommand( storagePoolId = c3715f80-6435-45b6-94c2-a9a2869cd059, ignoreFailoverLimit = false, storageDomainId = 8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b, imageGroupId = 9245c20e-e8f1-4c4d-955e-187bdb48d76d, imageId = 089f5d4a-52a9-435e-a3ad-944648c09095), log id: 10453fe0
2015-10-20 20:58:20,085 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-37) [ec60c56] -- executeIrsBrokerCommand: calling 'setVolumeDescription' 
2015-10-20 20:58:20,086 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-37) [ec60c56] -- setVolumeDescription parameters:
                spUUID=c3715f80-6435-45b6-94c2-a9a2869cd059
                sdUUID=8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b
                imageGroupGUID=9245c20e-e8f1-4c4d-955e-187bdb48d76d
                volUUID=089f5d4a-52a9-435e-a3ad-944648c09095
                description={"Updated":false,"Disk Description":"OVF_STORE","Storage Domains":[{"uuid":"8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b"}],"Last Updated":"Mon May 25 12:53:14 UTC 2015"}

2015-10-20 20:58:20,105 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-37) [ec60c56] FINISH, SetVolumeDescriptionVDSCommand, log id: 10453fe0
2015-10-20 20:58:20,295 INFO  [org.ovirt.engine.core.bll.UploadStreamCommand] (DefaultQuartzScheduler_Worker-37) [240018db] Lock Acquired to object EngineLock [exclusiveLocks= , sharedLocks= key: 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c value: VDS_EXECUTION
]
2015-10-20 20:58:20,303 INFO  [org.ovirt.engine.core.bll.UploadStreamCommand] (DefaultQuartzScheduler_Worker-37) [240018db] Running command: UploadStreamCommand internal: true. Entities affected :  ID: 8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b Type: Storage
2015-10-20 20:58:20,303 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-37) [240018db] START, UploadStreamVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c), log id: 20feaab7
2015-10-20 20:58:20,304 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-37) [240018db] -- executeVdsBrokerCommand: 
2015-10-20 20:58:20,304 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-37) [240018db] -- parameters:
                spUUID=c3715f80-6435-45b6-94c2-a9a2869cd059
                sdUUID=8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b
                imageGUID=9245c20e-e8f1-4c4d-955e-187bdb48d76d
                volUUID=089f5d4a-52a9-435e-a3ad-944648c09095
                size=296,960

2015-10-20 20:58:21,407 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-37) [240018db] FINISH, UploadStreamVDSCommand, log id: 20feaab7
2015-10-20 20:58:21,408 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-37) [240018db] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 2ceb36cd-bc65-4029-a469-a76cf18fcd0a
2015-10-20 20:58:21,408 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-37) [240018db] CommandMultiAsyncTasks::AttachTask: Attaching task 8503fba8-4e02-4299-84ee-5b2d3e590ac5 to command 2ceb36cd-bc65-4029-a469-a76cf18fcd0a.
2015-10-20 20:58:21,540 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-37) [240018db] Adding task 8503fba8-4e02-4299-84ee-5b2d3e590ac5 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
2015-10-20 20:58:21,723 INFO  [org.ovirt.engine.core.bll.UploadStreamCommand] (DefaultQuartzScheduler_Worker-37) [240018db] Lock freed to object EngineLock [exclusiveLocks= , sharedLocks= key: 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c value: VDS_EXECUTION
]
2015-10-20 20:58:21,725 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-37) [240018db] START, SetVolumeDescriptionVDSCommand( storagePoolId = c3715f80-6435-45b6-94c2-a9a2869cd059, ignoreFailoverLimit = false, storageDomainId = 8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b, imageGroupId = 9245c20e-e8f1-4c4d-955e-187bdb48d76d, imageId = 089f5d4a-52a9-435e-a3ad-944648c09095), log id: 568af047
2015-10-20 20:58:22,004 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-37) [240018db] -- executeIrsBrokerCommand: calling 'setVolumeDescription' 
2015-10-20 20:58:22,005 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-37) [240018db] -- setVolumeDescription parameters:
                spUUID=c3715f80-6435-45b6-94c2-a9a2869cd059
                sdUUID=8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b
                imageGroupGUID=9245c20e-e8f1-4c4d-955e-187bdb48d76d
                volUUID=089f5d4a-52a9-435e-a3ad-944648c09095
                description={"Updated":true,"Disk Description":"OVF_STORE","Storage Domains":[{"uuid":"8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b"}],"Last Updated":"Tue Oct 20 20:58:19 UTC 2015","Size":296960}

2015-10-20 20:58:22,025 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-37) [240018db] FINISH, SetVolumeDescriptionVDSCommand, log id: 568af047
2015-10-20 20:58:22,136 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-37) [240018db] START, SetVolumeDescriptionVDSCommand( storagePoolId = c3715f80-6435-45b6-94c2-a9a2869cd059, ignoreFailoverLimit = false, storageDomainId = 8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b, imageGroupId = 1a58df14-7211-48b3-896c-2d3b260e301a, imageId = bab5c8bc-69a8-442c-a9bd-be8acfb46b64), log id: aa39444
2015-10-20 20:58:22,136 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-37) [240018db] -- executeIrsBrokerCommand: calling 'setVolumeDescription' 
2015-10-20 20:58:22,137 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-37) [240018db] -- setVolumeDescription parameters:
                spUUID=c3715f80-6435-45b6-94c2-a9a2869cd059
                sdUUID=8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b
                imageGroupGUID=1a58df14-7211-48b3-896c-2d3b260e301a
                volUUID=bab5c8bc-69a8-442c-a9bd-be8acfb46b64
                description={"Updated":false,"Disk Description":"OVF_STORE","Storage Domains":[{"uuid":"8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b"}],"Last Updated":"Mon May 25 12:53:14 UTC 2015"}

2015-10-20 20:58:22,158 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-37) [240018db] FINISH, SetVolumeDescriptionVDSCommand, log id: aa39444
2015-10-20 20:58:22,428 INFO  [org.ovirt.engine.core.bll.UploadStreamCommand] (DefaultQuartzScheduler_Worker-37) [3416f296] Lock Acquired to object EngineLock [exclusiveLocks= , sharedLocks= key: 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c value: VDS_EXECUTION
]
2015-10-20 20:58:22,436 INFO  [org.ovirt.engine.core.bll.UploadStreamCommand] (DefaultQuartzScheduler_Worker-37) [3416f296] Running command: UploadStreamCommand internal: true. Entities affected :  ID: 8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b Type: Storage
2015-10-20 20:58:22,437 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-37) [3416f296] START, UploadStreamVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c), log id: 62dc7ede
2015-10-20 20:58:22,437 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-37) [3416f296] -- executeVdsBrokerCommand: 
2015-10-20 20:58:22,437 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-37) [3416f296] -- parameters:
                spUUID=c3715f80-6435-45b6-94c2-a9a2869cd059
                sdUUID=8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b
                imageGUID=1a58df14-7211-48b3-896c-2d3b260e301a
                volUUID=bab5c8bc-69a8-442c-a9bd-be8acfb46b64
                size=296,960

2015-10-20 20:58:23,511 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-37) [3416f296] FINISH, UploadStreamVDSCommand, log id: 62dc7ede
2015-10-20 20:58:23,512 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-37) [3416f296] CommandMultiAsyncTasks::AttachTask: Attaching task dd3b4ffe-c618-43a7-9cec-cb05954f5ba5 to command 2ceb36cd-bc65-4029-a469-a76cf18fcd0a.
2015-10-20 20:58:23,623 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-37) [3416f296] Adding task dd3b4ffe-c618-43a7-9cec-cb05954f5ba5 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
2015-10-20 20:58:23,703 INFO  [org.ovirt.engine.core.bll.UploadStreamCommand] (DefaultQuartzScheduler_Worker-37) [3416f296] Lock freed to object EngineLock [exclusiveLocks= , sharedLocks= key: 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c value: VDS_EXECUTION
]
2015-10-20 20:58:23,704 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-37) [3416f296] START, SetVolumeDescriptionVDSCommand( storagePoolId = c3715f80-6435-45b6-94c2-a9a2869cd059, ignoreFailoverLimit = false, storageDomainId = 8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b, imageGroupId = 1a58df14-7211-48b3-896c-2d3b260e301a, imageId = bab5c8bc-69a8-442c-a9bd-be8acfb46b64), log id: 5fed4d9e
2015-10-20 20:58:23,705 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-37) [3416f296] -- executeIrsBrokerCommand: calling 'setVolumeDescription' 
2015-10-20 20:58:23,705 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-37) [3416f296] -- setVolumeDescription parameters:
                spUUID=c3715f80-6435-45b6-94c2-a9a2869cd059
                sdUUID=8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b
                imageGroupGUID=1a58df14-7211-48b3-896c-2d3b260e301a
                volUUID=bab5c8bc-69a8-442c-a9bd-be8acfb46b64
                description={"Updated":true,"Disk Description":"OVF_STORE","Storage Domains":[{"uuid":"8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b"}],"Last Updated":"Tue Oct 20 20:58:19 UTC 2015","Size":296960}

2015-10-20 20:58:23,724 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-37) [3416f296] FINISH, SetVolumeDescriptionVDSCommand, log id: 5fed4d9e
2015-10-20 20:58:24,107 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-37) [3416f296] BaseAsyncTask::startPollingTask: Starting to poll task 8503fba8-4e02-4299-84ee-5b2d3e590ac5.
2015-10-20 20:58:24,107 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-37) [3416f296] BaseAsyncTask::startPollingTask: Starting to poll task dd3b4ffe-c618-43a7-9cec-cb05954f5ba5.
2015-10-20 20:58:24,107 INFO  [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (DefaultQuartzScheduler_Worker-37) [3416f296] Lock freed to object EngineLock [exclusiveLocks= key: 8fd14fae-9cd2-40f8-aa12-5b6a4f21c57b value: STORAGE
key: 1a58df14-7211-48b3-896c-2d3b260e301a value: DISK
key: 9245c20e-e8f1-4c4d-955e-187bdb48d76d value: DISK
, sharedLocks= key: c3715f80-6435-45b6-94c2-a9a2869cd059 value: OVF_UPDATE
]
2015-10-20 20:58:30,720 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-8) [5c8d6135] Polling and updating Async Tasks: 2 tasks, 2 tasks to poll now
2015-10-20 20:58:30,724 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-8) [5c8d6135] SPMAsyncTask::PollTask: Polling task dd3b4ffe-c618-43a7-9cec-cb05954f5ba5 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
2015-10-20 20:58:30,725 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-8) [5c8d6135] BaseAsyncTask::onTaskEndSuccess: Task dd3b4ffe-c618-43a7-9cec-cb05954f5ba5 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
2015-10-20 20:58:30,725 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-8) [5c8d6135] Task with DB Task ID 56e3605e-0aee-4626-8072-8fac0a9806dc and VDSM Task ID 8503fba8-4e02-4299-84ee-5b2d3e590ac5 is in state Polling. End action for command 2ceb36cd-bc65-4029-a469-a76cf18fcd0a will proceed when all the entitys tasks are completed.
2015-10-20 20:58:30,726 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-8) [5c8d6135] SPMAsyncTask::PollTask: Polling task 8503fba8-4e02-4299-84ee-5b2d3e590ac5 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
2015-10-20 20:58:30,726 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-8) [5c8d6135] BaseAsyncTask::onTaskEndSuccess: Task 8503fba8-4e02-4299-84ee-5b2d3e590ac5 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
2015-10-20 20:58:30,727 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-8) [5c8d6135] CommandAsyncTask::endActionIfNecessary: All tasks of command 2ceb36cd-bc65-4029-a469-a76cf18fcd0a has ended -> executing endAction
2015-10-20 20:58:30,727 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-8) [5c8d6135] CommandAsyncTask::endAction: Ending action for 2 tasks (command ID: 2ceb36cd-bc65-4029-a469-a76cf18fcd0a): calling endAction .
2015-10-20 20:58:30,728 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-6) [5c8d6135] CommandAsyncTask::endCommandAction [within thread] context: Attempting to endAction ProcessOvfUpdateForStorageDomain, executionIndex: 0
2015-10-20 20:58:30,754 INFO  [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (org.ovirt.thread.pool-8-thread-6) [ec60c56] Ending command successfully: org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand
2015-10-20 20:58:30,755 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-6) [ec60c56] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ProcessOvfUpdateForStorageDomain completed, handling the result.
2015-10-20 20:58:30,755 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-6) [ec60c56] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ProcessOvfUpdateForStorageDomain succeeded, clearing tasks.
2015-10-20 20:58:30,755 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-6) [ec60c56] SPMAsyncTask::ClearAsyncTask: Attempting to clear task dd3b4ffe-c618-43a7-9cec-cb05954f5ba5
2015-10-20 20:58:30,756 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-6) [ec60c56] START, SPMClearTaskVDSCommand( storagePoolId = c3715f80-6435-45b6-94c2-a9a2869cd059, ignoreFailoverLimit = false, taskId = dd3b4ffe-c618-43a7-9cec-cb05954f5ba5), log id: 2dba7afe
2015-10-20 20:58:30,756 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-6) [ec60c56] START, HSMClearTaskVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, taskId=dd3b4ffe-c618-43a7-9cec-cb05954f5ba5), log id: 2de0d126
2015-10-20 20:58:30,764 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-6) [ec60c56] FINISH, HSMClearTaskVDSCommand, log id: 2de0d126
2015-10-20 20:58:30,765 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-6) [ec60c56] FINISH, SPMClearTaskVDSCommand, log id: 2dba7afe
2015-10-20 20:58:30,776 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-6) [ec60c56] BaseAsyncTask::removeTaskFromDB: Removed task dd3b4ffe-c618-43a7-9cec-cb05954f5ba5 from DataBase
2015-10-20 20:58:30,776 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-6) [ec60c56] SPMAsyncTask::ClearAsyncTask: Attempting to clear task 8503fba8-4e02-4299-84ee-5b2d3e590ac5
2015-10-20 20:58:30,776 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-6) [ec60c56] START, SPMClearTaskVDSCommand( storagePoolId = c3715f80-6435-45b6-94c2-a9a2869cd059, ignoreFailoverLimit = false, taskId = 8503fba8-4e02-4299-84ee-5b2d3e590ac5), log id: 429495f9
2015-10-20 20:58:30,777 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-6) [ec60c56] START, HSMClearTaskVDSCommand(HostName = esther, HostId = 7ad70ac5-cbdd-4bff-b5f5-7f04ce22810c, taskId=8503fba8-4e02-4299-84ee-5b2d3e590ac5), log id: 29023095
2015-10-20 20:58:30,784 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-6) [ec60c56] FINISH, HSMClearTaskVDSCommand, log id: 29023095
2015-10-20 20:58:30,784 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-6) [ec60c56] FINISH, SPMClearTaskVDSCommand, log id: 429495f9
2015-10-20 20:58:30,792 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-6) [ec60c56] BaseAsyncTask::removeTaskFromDB: Removed task 8503fba8-4e02-4299-84ee-5b2d3e590ac5 from DataBase
2015-10-20 20:58:30,793 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-6) [ec60c56] CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 2ceb36cd-bc65-4029-a469-a76cf18fcd0a
2015-10-20 20:59:51,360 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-25) [6cd4a8f6] Setting new tasks map. The map contains now 0 tasks
2015-10-20 20:59:51,360 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-25) [6cd4a8f6] Cleared all tasks of pool c3715f80-6435-45b6-94c2-a9a2869cd059.
2015-10-20 21:08:59,517 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Loaded file "/usr/share/ovirt-engine/services/ovirt-engine/ovirt-engine.conf".
2015-10-20 21:08:59,517 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) The file "/etc/ovirt-engine/engine.conf" doesn't exist or isn't readable. Will return an empty set of properties.
