- Users - oVirt List Archives

oVirt 3.5.3.1 - Clone_VM Process deleted Source-VM and the the Clone-VM does not contain any disk anymore
by Christian Rebel 31 Aug '15

31 Aug '15

This is a multipart message in MIME format. ------=_NextPart_000_0183_01D0E19A.D0BB8DB0 Content-Type: text/plain; charset="us-ascii" Content-Transfer-Encoding: 7bit Dear all, I have started a Clone_VM over the GUI, but now the Source-VM has been deleted and the Target-VM does not contain any disk! The Task is displaying that "Copying Image" and " Finalize" has been failed, I hope there is a way to restore the VM somehow - please help me. >From the Logfile: 2015-08-28 12:47:20,950 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-7) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VM Katello is down. Exit message: User shut down from within the guest 2015-08-28 12:47:20,955 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-7) VM Katello (9013e3c2-3cd7-4eae-a3e6-f5e83a64db87) is running in db and not running in VDS itsatltovirtaio.domain.local 2015-08-28 12:47:20,957 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-7) START, FullListVdsCommand(HostName = itsatltovirtaio.domain.local, HostId = b783a2ee-4a63-46ca-9afc-b3b74f0e10ce, vds=Host[itsatltovirtaio.domain.local,b783a2ee-4a63-46ca-9afc-b3b74f0e10ce], vmIds=[9013e3c2-3cd7-4eae-a3e6-f5e83a64db87]), log id: 39590448 2015-08-28 12:47:20,966 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-7) FINISH, FullListVdsCommand, return: [], log id: 39590448 2015-08-28 12:47:21,046 INFO [org.ovirt.engine.core.bll.ProcessDownVmCommand] (org.ovirt.thread.pool-8-thread-17) [82bee5d] Running command: ProcessDownVmCommand internal: true. 2015-08-28 12:47:24,589 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-3) Polling and updating Async Tasks: 2 tasks, 2 tasks to poll now 2015-08-28 12:47:24,600 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-3) SPMAsyncTask::PollTask: Polling task 037b2c85-68d2-4159-8310-91c472038b5b (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. 2015-08-28 12:47:24,603 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-3) BaseAsyncTask::onTaskEndSuccess: Task 037b2c85-68d2-4159-8310-91c472038b5b (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. 2015-08-28 12:47:24,604 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-3) Task with DB Task ID 0e6a6d72-0cea-41aa-8fe9-9262bc53d558 and VDSM Task ID cd125365-3344-4f45-b67a-39c2fa5112ab is in state Polling. End action for command e9edfed0-915a-4534-b774-c07682bafa59 will proceed when all the entitys tasks are completed. 2015-08-28 12:47:24,605 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-3) SPMAsyncTask::PollTask: Polling task cd125365-3344-4f45-b67a-39c2fa5112ab (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. 2015-08-28 12:47:24,606 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-3) BaseAsyncTask::onTaskEndSuccess: Task cd125365-3344-4f45-b67a-39c2fa5112ab (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. 2015-08-28 12:47:24,606 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-3) CommandAsyncTask::endActionIfNecessary: All tasks of command e9edfed0-915a-4534-b774-c07682bafa59 has ended -> executing endAction 2015-08-28 12:47:24,607 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-3) CommandAsyncTask::endAction: Ending action for 2 tasks (command ID: e9edfed0-915a-4534-b774-c07682bafa59): calling endAction . 2015-08-28 12:47:24,607 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-33) CommandAsyncTask::endCommandAction [within thread] context: Attempting to endAction ProcessOvfUpdateForStorageDomain, executionIndex: 0 2015-08-28 12:47:24,668 INFO [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (org.ovirt.thread.pool-8-thread-33) [484cded8] Ending command successfully: org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand 2015-08-28 12:47:24,669 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-33) [484cded8] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ProcessOvfUpdateForStorageDomain completed, handling the result. 2015-08-28 12:47:24,670 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-33) [484cded8] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ProcessOvfUpdateForStorageDomain succeeded, clearing tasks. 2015-08-28 12:47:24,671 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-33) [484cded8] SPMAsyncTask::ClearAsyncTask: Attempting to clear task cd125365-3344-4f45-b67a-39c2fa5112ab 2015-08-28 12:47:24,671 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-33) [484cded8] START, SPMClearTaskVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit = false, taskId = cd125365-3344-4f45-b67a-39c2fa5112ab), log id: 73f5ca79 2015-08-28 12:47:24,700 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-33) [484cded8] START, HSMClearTaskVDSCommand(HostName = itsatltovirtaio.domain.local, HostId = b783a2ee-4a63-46ca-9afc-b3b74f0e10ce, taskId=cd125365-3344-4f45-b67a-39c2fa5112ab), log id: dc00969 2015-08-28 12:47:24,726 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-33) [484cded8] FINISH, HSMClearTaskVDSCommand, log id: dc00969 2015-08-28 12:47:24,728 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-33) [484cded8] FINISH, SPMClearTaskVDSCommand, log id: 73f5ca79 2015-08-28 12:47:24,742 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-33) [484cded8] BaseAsyncTask::removeTaskFromDB: Removed task cd125365-3344-4f45-b67a-39c2fa5112ab from DataBase 2015-08-28 12:47:24,744 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-33) [484cded8] SPMAsyncTask::ClearAsyncTask: Attempting to clear task 037b2c85-68d2-4159-8310-91c472038b5b 2015-08-28 12:47:24,745 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-33) [484cded8] START, SPMClearTaskVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit = false, taskId = 037b2c85-68d2-4159-8310-91c472038b5b), log id: 3b75651d 2015-08-28 12:47:24,776 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-33) [484cded8] START, HSMClearTaskVDSCommand(HostName = itsatltovirtaio.domain.local, HostId = b783a2ee-4a63-46ca-9afc-b3b74f0e10ce, taskId=037b2c85-68d2-4159-8310-91c472038b5b), log id: 1841716a 2015-08-28 12:47:24,793 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-33) [484cded8] FINISH, HSMClearTaskVDSCommand, log id: 1841716a 2015-08-28 12:47:24,795 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-33) [484cded8] FINISH, SPMClearTaskVDSCommand, log id: 3b75651d 2015-08-28 12:47:24,808 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-33) [484cded8] BaseAsyncTask::removeTaskFromDB: Removed task 037b2c85-68d2-4159-8310-91c472038b5b from DataBase 2015-08-28 12:47:24,810 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-33) [484cded8] CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity e9edfed0-915a-4534-b774-c07682bafa59 2015-08-28 12:48:09,771 WARN [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (ajp--127.0.0.1-8702-9) [c1d5ad4] The message key CloneVm is missing from bundles/ExecutionMessages 2015-08-28 12:48:09,966 INFO [org.ovirt.engine.core.bll.CloneVmCommand] (ajp--127.0.0.1-8702-9) [c1d5ad4] Lock Acquired to object EngineLock [exclusiveLocks= key: Katello_2.3 value: VM_NAME , sharedLocks= key: e7e99288-ad83-406e-9cb6-7a5aa443de9b value: DISK key: 6281b597-020d-4ea7-a954-bb798a0ca4f1 value: DISK key: 9013e3c2-3cd7-4eae-a3e6-f5e83a64db87 value: VM ] 2015-08-28 12:48:10,691 INFO [org.ovirt.engine.core.bll.CloneVmCommand] (ajp--127.0.0.1-8702-9) [c1d5ad4] Running command: CloneVmCommand internal: false. Entities affected : ID: 9013e3c2-3cd7-4eae-a3e6-f5e83a64db87 Type: VMAction group CREATE_VM with role type USER 2015-08-28 12:48:11,370 INFO [org.ovirt.engine.core.bll.CopyImageGroupCommand] (ajp--127.0.0.1-8702-9) [7bfcbcdd] Running command: CopyImageGroupCommand internal: true. Entities affected : ID: 937822d9-8a59-490f-95b7-48371ae32253 Type: Storage 2015-08-28 12:48:11,529 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp--127.0.0.1-8702-9) [7bfcbcdd] START, CopyImageVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit = false, storageDomainId = 937822d9-8a59-490f-95b7-48371ae32253, imageGroupId = 6281b597-020d-4ea7-a954-bb798a0ca4f1, imageId = 2a2015a1-f62c-4e32-8b04-77ece2ba4cc1, dstImageGroupId = e64d7be5-7643-4ba1-b347-80c923f130e6, vmId = ee2ea036-2af3-4a18-9329-08a7b0e7ce7c, dstImageId = 6364bb72-8cb6-49c0-9673-f01ec49b88f2, imageDescription = , dstStorageDomainId = 937822d9-8a59-490f-95b7-48371ae32253, copyVolumeType = LeafVol, volumeFormat = RAW, preallocate = Sparse, postZero = false, force = false), log id: 3caf0bb2 2015-08-28 12:48:11,537 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp--127.0.0.1-8702-9) [7bfcbcdd] -- executeIrsBrokerCommand: calling 'copyImage' with two new parameters: description and UUID 2015-08-28 12:48:11,539 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp--127.0.0.1-8702-9) [7bfcbcdd] -- copyImage parameters: sdUUID=937822d9-8a59-490f-95b7-48371ae32253 spUUID=00000002-0002-0002-0002-000000000021 vmGUID=ee2ea036-2af3-4a18-9329-08a7b0e7ce7c srcImageGUID=6281b597-020d-4ea7-a954-bb798a0ca4f1 srcVolUUID=2a2015a1-f62c-4e32-8b04-77ece2ba4cc1 dstImageGUID=e64d7be5-7643-4ba1-b347-80c923f130e6 dstVolUUID=6364bb72-8cb6-49c0-9673-f01ec49b88f2 descr= dstSdUUID=937822d9-8a59-490f-95b7-48371ae32253 2015-08-28 12:48:13,882 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp--127.0.0.1-8702-9) [7bfcbcdd] FINISH, CopyImageVDSCommand, return: 00000000-0000-0000-0000-000000000000, log id: 3caf0bb2 2015-08-28 12:48:13,909 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (ajp--127.0.0.1-8702-9) [7bfcbcdd] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command f81024b3-e534-4df4-907b-91e690bf049c 2015-08-28 12:48:13,912 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp--127.0.0.1-8702-9) [7bfcbcdd] CommandMultiAsyncTasks::AttachTask: Attaching task 11079859-f235-4227-b2ba-b51f88a0f9cd to command f81024b3-e534-4df4-907b-91e690bf049c. 2015-08-28 12:48:13,941 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (ajp--127.0.0.1-8702-9) [7bfcbcdd] Adding task 11079859-f235-4227-b2ba-b51f88a0f9cd (Parent Command CloneVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. 2015-08-28 12:48:14,043 INFO [org.ovirt.engine.core.bll.CopyImageGroupCommand] (ajp--127.0.0.1-8702-9) [89f7645] Running command: CopyImageGroupCommand internal: true. Entities affected : ID: 937822d9-8a59-490f-95b7-48371ae32253 Type: Storage 2015-08-28 12:48:14,083 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp--127.0.0.1-8702-9) [89f7645] START, CopyImageVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit = false, storageDomainId = 937822d9-8a59-490f-95b7-48371ae32253, imageGroupId = e7e99288-ad83-406e-9cb6-7a5aa443de9b, imageId = c5762dec-d9d1-4842-84d1-05896d4d27fb, dstImageGroupId = 7f5dd048-048f-49c1-9589-c935fcdccfdd, vmId = ee2ea036-2af3-4a18-9329-08a7b0e7ce7c, dstImageId = f57531cf-fb00-4a4d-9aad-c3ffa01f58ce, imageDescription = , dstStorageDomainId = 937822d9-8a59-490f-95b7-48371ae32253, copyVolumeType = LeafVol, volumeFormat = RAW, preallocate = Sparse, postZero = false, force = false), log id: 11b82b6e 2015-08-28 12:48:14,086 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp--127.0.0.1-8702-9) [89f7645] -- executeIrsBrokerCommand: calling 'copyImage' with two new parameters: description and UUID 2015-08-28 12:48:14,087 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp--127.0.0.1-8702-9) [89f7645] -- copyImage parameters: sdUUID=937822d9-8a59-490f-95b7-48371ae32253 spUUID=00000002-0002-0002-0002-000000000021 vmGUID=ee2ea036-2af3-4a18-9329-08a7b0e7ce7c srcImageGUID=e7e99288-ad83-406e-9cb6-7a5aa443de9b srcVolUUID=c5762dec-d9d1-4842-84d1-05896d4d27fb dstImageGUID=7f5dd048-048f-49c1-9589-c935fcdccfdd dstVolUUID=f57531cf-fb00-4a4d-9aad-c3ffa01f58ce descr= dstSdUUID=937822d9-8a59-490f-95b7-48371ae32253 2015-08-28 12:48:17,722 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp--127.0.0.1-8702-9) [89f7645] FINISH, CopyImageVDSCommand, return: 00000000-0000-0000-0000-000000000000, log id: 11b82b6e 2015-08-28 12:48:17,753 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp--127.0.0.1-8702-9) [89f7645] CommandMultiAsyncTasks::AttachTask: Attaching task a961301c-0da0-49b6-a2e7-787de9ea8fe6 to command f81024b3-e534-4df4-907b-91e690bf049c. 2015-08-28 12:48:17,785 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (ajp--127.0.0.1-8702-9) [89f7645] Adding task a961301c-0da0-49b6-a2e7-787de9ea8fe6 (Parent Command CloneVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. 2015-08-28 12:48:18,136 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-9) [89f7645] Correlation ID: c1d5ad4, Job ID: e36f06d1-3c60-4b4c-86db-0ba5f903b254, Call Stack: null, Custom Event ID: -1, Message: VM Katello_2.3 creation was initiated by admin@internal. 2015-08-28 12:48:18,138 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (ajp--127.0.0.1-8702-9) [89f7645] BaseAsyncTask::startPollingTask: Starting to poll task 11079859-f235-4227-b2ba-b51f88a0f9cd. 2015-08-28 12:48:18,140 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (ajp--127.0.0.1-8702-9) [89f7645] BaseAsyncTask::startPollingTask: Starting to poll task a961301c-0da0-49b6-a2e7-787de9ea8fe6. 2015-08-28 12:48:24,607 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-61) Polling and updating Async Tasks: 4 tasks, 2 tasks to poll now 2015-08-28 12:48:24,621 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSCommand] (DefaultQuartzScheduler_Worker-61) Failed in HSMGetAllTasksStatusesVDS method 2015-08-28 12:48:24,622 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSCommand] (DefaultQuartzScheduler_Worker-61) Failed in HSMGetAllTasksStatusesVDS method 2015-08-28 12:48:24,624 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-61) SPMAsyncTask::PollTask: Polling task a961301c-0da0-49b6-a2e7-787de9ea8fe6 (Parent Command CloneVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'cleanSuccess'. 2015-08-28 12:48:24,635 ERROR [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-61) BaseAsyncTask::logEndTaskFailure: Task a961301c-0da0-49b6-a2e7-787de9ea8fe6 (Parent Command CloneVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended with failure: -- Result: cleanSuccess -- Message: VDSGenericException: VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error = Volume does not exist, code = 201, -- Exception: VDSGenericException: VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error = Volume does not exist, code = 201 2015-08-28 12:48:24,640 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-61) Task with DB Task ID ed763e40-d1e9-4c36-8822-e6b232a864b0 and VDSM Task ID 11079859-f235-4227-b2ba-b51f88a0f9cd is in state Polling. End action for command f81024b3-e534-4df4-907b-91e690bf049c will proceed when all the entitys tasks are completed. 2015-08-28 12:48:24,643 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-61) SPMAsyncTask::PollTask: Polling task 11079859-f235-4227-b2ba-b51f88a0f9cd (Parent Command CloneVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'cleanSuccess'. 2015-08-28 12:48:24,652 ERROR [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-61) BaseAsyncTask::logEndTaskFailure: Task 11079859-f235-4227-b2ba-b51f88a0f9cd (Parent Command CloneVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended with failure: -- Result: cleanSuccess -- Message: VDSGenericException: VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error = Volume does not exist, code = 201, -- Exception: VDSGenericException: VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error = Volume does not exist, code = 201 2015-08-28 12:48:24,659 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-61) CommandAsyncTask::endActionIfNecessary: All tasks of command f81024b3-e534-4df4-907b-91e690bf049c has ended -> executing endAction 2015-08-28 12:48:24,662 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-61) CommandAsyncTask::endAction: Ending action for 2 tasks (command ID: f81024b3-e534-4df4-907b-91e690bf049c): calling endAction . 2015-08-28 12:48:24,664 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-2) CommandAsyncTask::endCommandAction [within thread] context: Attempting to endAction CloneVm, executionIndex: 0 2015-08-28 12:48:24,827 ERROR [org.ovirt.engine.core.bll.CloneVmCommand] (org.ovirt.thread.pool-8-thread-2) [c1d5ad4] Ending command with failure: org.ovirt.engine.core.bll.CloneVmCommand 2015-08-28 12:48:24,844 ERROR [org.ovirt.engine.core.bll.CopyImageGroupCommand] (org.ovirt.thread.pool-8-thread-2) [89f7645] Ending command with failure: org.ovirt.engine.core.bll.CopyImageGroupCommand 2015-08-28 12:48:24,853 INFO [org.ovirt.engine.core.bll.RemoveImageCommand] (org.ovirt.thread.pool-8-thread-2) [779578db] Running command: RemoveImageCommand internal: true. Entities affected : ID: 937822d9-8a59-490f-95b7-48371ae32253 Type: Storage 2015-08-28 12:48:24,913 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (org.ovirt.thread.pool-8-thread-2) [779578db] START, DeleteImageGroupVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit = false, storageDomainId = 937822d9-8a59-490f-95b7-48371ae32253, imageGroupId = 7f5dd048-048f-49c1-9589-c935fcdccfdd, postZeros = false, forceDelete = false), log id: 6bc5ac77 2015-08-28 12:48:24,979 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (org.ovirt.thread.pool-8-thread-2) [779578db] Command DeleteImageGroupVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit = false, storageDomainId = 937822d9-8a59-490f-95b7-48371ae32253, imageGroupId = 7f5dd048-048f-49c1-9589-c935fcdccfdd, postZeros = false, forceDelete = false) execution failed. Exception: IrsOperationFailedNoFailoverException: IRSGenericException: IRSErrorException: Image does not exist in domain: u'image=7f5dd048-048f-49c1-9589-c935fcdccfdd, domain=937822d9-8a59-490f-95b7-48371ae32253' 2015-08-28 12:48:24,984 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (org.ovirt.thread.pool-8-thread-2) [779578db] FINISH, DeleteImageGroupVDSCommand, log id: 6bc5ac77 2015-08-28 12:48:24,986 INFO [org.ovirt.engine.core.bll.RemoveImageCommand] (org.ovirt.thread.pool-8-thread-2) [779578db] Disk 7f5dd048-048f-49c1-9589-c935fcdccfdd doesnt exist on storage domain 937822d9-8a59-490f-95b7-48371ae32253, rolling forward 2015-08-28 12:48:25,126 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-8-thread-2) [779578db] Removed task da4b9e1f-43f3-4de0-8f38-bdc5bc8ebaae from DataBase 2015-08-28 12:48:25,144 ERROR [org.ovirt.engine.core.bll.CopyImageGroupCommand] (org.ovirt.thread.pool-8-thread-2) [7bfcbcdd] Ending command with failure: org.ovirt.engine.core.bll.CopyImageGroupCommand 2015-08-28 12:48:25,152 INFO [org.ovirt.engine.core.bll.RemoveImageCommand] (org.ovirt.thread.pool-8-thread-2) [2a0d85fe] Running command: RemoveImageCommand internal: true. Entities affected : ID: 937822d9-8a59-490f-95b7-48371ae32253 Type: Storage 2015-08-28 12:48:25,203 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (org.ovirt.thread.pool-8-thread-2) [2a0d85fe] START, DeleteImageGroupVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit = false, storageDomainId = 937822d9-8a59-490f-95b7-48371ae32253, imageGroupId = e64d7be5-7643-4ba1-b347-80c923f130e6, postZeros = false, forceDelete = false), log id: ff00a91 2015-08-28 12:48:25,283 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (org.ovirt.thread.pool-8-thread-2) [2a0d85fe] Command DeleteImageGroupVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit = false, storageDomainId = 937822d9-8a59-490f-95b7-48371ae32253, imageGroupId = e64d7be5-7643-4ba1-b347-80c923f130e6, postZeros = false, forceDelete = false) execution failed. Exception: IrsOperationFailedNoFailoverException: IRSGenericException: IRSErrorException: Image does not exist in domain: u'image=e64d7be5-7643-4ba1-b347-80c923f130e6, domain=937822d9-8a59-490f-95b7-48371ae32253' 2015-08-28 12:48:25,290 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (org.ovirt.thread.pool-8-thread-2) [2a0d85fe] FINISH, DeleteImageGroupVDSCommand, log id: ff00a91 2015-08-28 12:48:25,292 INFO [org.ovirt.engine.core.bll.RemoveImageCommand] (org.ovirt.thread.pool-8-thread-2) [2a0d85fe] Disk e64d7be5-7643-4ba1-b347-80c923f130e6 doesnt exist on storage domain 937822d9-8a59-490f-95b7-48371ae32253, rolling forward 2015-08-28 12:48:25,406 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-8-thread-2) [2a0d85fe] Removed task 8ad7c433-a77f-4003-a11e-f061ca6cf844 from DataBase 2015-08-28 12:48:25,613 INFO [org.ovirt.engine.core.bll.CloneVmCommand] (org.ovirt.thread.pool-8-thread-2) Lock freed to object EngineLock [exclusiveLocks= key: Katello_2.3 value: VM_NAME , sharedLocks= key: e7e99288-ad83-406e-9cb6-7a5aa443de9b value: DISK key: 6281b597-020d-4ea7-a954-bb798a0ca4f1 value: DISK key: 9013e3c2-3cd7-4eae-a3e6-f5e83a64db87 value: VM ] 2015-08-28 12:48:25,676 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogableBase] (org.ovirt.thread.pool-8-thread-2) Error during log command: org.ovirt.engine.core.bll.CloneVmCommand. Exception java.lang.NullPointerException: java.lang.NullPointerException at org.ovirt.engine.core.bll.CloneVmCommand.getVm(CloneVmCommand.java:140) [bll.jar:] at org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogableBase.getVds( AuditLogableBase.java:353) [dal.jar:] at org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogableBase.getVdsI dRef(AuditLogableBase.java:237) [dal.jar:] at org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector.saveToD b(AuditLogDirector.java:140) [dal.jar:] at org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector.log(Aud itLogDirector.java:107) [dal.jar:] at org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector.log(Aud itLogDirector.java:100) [dal.jar:] at org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector.log(Aud itLogDirector.java:96) [dal.jar:] at org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogableBase.log(Aud itLogableBase.java:459) [dal.jar:] at org.ovirt.engine.core.bll.CommandBase.logCommand(CommandBase.java:1447) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.endAction(CommandBase.java:513) [bll.jar:] at org.ovirt.engine.core.bll.tasks.DecoratedCommand.endAction(DecoratedCommand. java:17) [bll.jar:] at org.ovirt.engine.core.bll.tasks.CoCoAsyncTaskHelper.endAction(CoCoAsyncTaskH elper.java:337) [bll.jar:] at org.ovirt.engine.core.bll.tasks.CommandCoordinatorImpl.endAction(CommandCoor dinatorImpl.java:383) [bll.jar:] at org.ovirt.engine.core.bll.tasks.CommandAsyncTask.endCommandAction(CommandAsy ncTask.java:149) [bll.jar:] at org.ovirt.engine.core.bll.tasks.CommandAsyncTask.access$000(CommandAsyncTask .java:28) [bll.jar:] at org.ovirt.engine.core.bll.tasks.CommandAsyncTask$1.run(CommandAsyncTask.java :109) [bll.jar:] at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnabl e.run(ThreadPoolUtil.java:90) [utils.jar:] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_79] at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_79] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:11 45) [rt.jar:1.7.0_79] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:6 15) [rt.jar:1.7.0_79] at java.lang.Thread.run(Thread.java:745) [rt.jar:1.7.0_79] 2015-08-28 12:48:25,700 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-2) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type CloneVm completed, handling the result. 2015-08-28 12:48:25,703 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-2) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type CloneVm succeeded, clearing tasks. 2015-08-28 12:48:25,714 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-2) SPMAsyncTask::ClearAsyncTask: Attempting to clear task a961301c-0da0-49b6-a2e7-787de9ea8fe6 2015-08-28 12:48:25,715 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-2) START, SPMClearTaskVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit = false, taskId = a961301c-0da0-49b6-a2e7-787de9ea8fe6), log id: 4852f196 2015-08-28 12:48:25,745 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-2) START, HSMClearTaskVDSCommand(HostName = itsatltovirtaio.domain.local, HostId = b783a2ee-4a63-46ca-9afc-b3b74f0e10ce, taskId=a961301c-0da0-49b6-a2e7-787de9ea8fe6), log id: 3d72d3f2 2015-08-28 12:48:25,766 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-2) FINISH, HSMClearTaskVDSCommand, log id: 3d72d3f2 2015-08-28 12:48:25,767 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-2) FINISH, SPMClearTaskVDSCommand, log id: 4852f196 2015-08-28 12:48:25,780 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-2) BaseAsyncTask::removeTaskFromDB: Removed task a961301c-0da0-49b6-a2e7-787de9ea8fe6 from DataBase 2015-08-28 12:48:25,782 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-2) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 11079859-f235-4227-b2ba-b51f88a0f9cd 2015-08-28 12:48:25,784 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-2) START, SPMClearTaskVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit = false, taskId = 11079859-f235-4227-b2ba-b51f88a0f9cd), log id: 13acb232 2015-08-28 12:48:25,821 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-2) START, HSMClearTaskVDSCommand(HostName = itsatltovirtaio.domain.local, HostId = b783a2ee-4a63-46ca-9afc-b3b74f0e10ce, taskId=11079859-f235-4227-b2ba-b51f88a0f9cd), log id: 2ee6fab5 2015-08-28 12:48:25,842 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-2) FINISH, HSMClearTaskVDSCommand, log id: 2ee6fab5 2015-08-28 12:48:25,844 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-2) FINISH, SPMClearTaskVDSCommand, log id: 13acb232 2015-08-28 12:48:25,855 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-2) BaseAsyncTask::removeTaskFromDB: Removed task 11079859-f235-4227-b2ba-b51f88a0f9cd from DataBase 2015-08-28 12:48:25,857 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-2) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity f81024b3-e534-4df4-907b-91e690bf049c 2015-08-28 12:48:41,695 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-99) Setting new tasks map. The map contains now 2 tasks 2015-08-28 12:49:41,697 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-49) Setting new tasks map. The map contains now 0 tasks 2015-08-28 12:49:41,697 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-49) Cleared all tasks of pool 00000002-0002-0002-0002-000000000021. ------=_NextPart_000_0183_01D0E19A.D0BB8DB0 Content-Type: text/html; charset="us-ascii" Content-Transfer-Encoding: quoted-printable <html xmlns:v=3D"urn:schemas-microsoft-com:vml" = xmlns:o=3D"urn:schemas-microsoft-com:office:office" = xmlns:w=3D"urn:schemas-microsoft-com:office:word" = xmlns:m=3D"http://schemas.microsoft.com/office/2004/12/omml" = xmlns=3D"http://www.w3.org/TR/REC-html40"><head><meta = http-equiv=3DContent-Type content=3D"text/html; = charset=3Dus-ascii"><meta name=3DGenerator content=3D"Microsoft Word 14 = (filtered medium)"><style></style></head><body lang=3DDE-AT link=3Dblue = vlink=3Dpurple><div class=3DWordSection1>Dear all,<o:p></o:p><o:p> </o:p>I have = started a Clone_VM over the GUI, but now the Source-VM has been deleted = and the Target-VM does not contain any disk!<o:p></o:p>The Task is = displaying that “Copying Image” and “ Finalize” = has been failed, I hope there is a way to restore the VM somehow – = please help me…<o:p></o:p><o:p> </o:p>From the = Logfile:<o:p></o:p><o:p> </o:p>2015-08-28 = 12:47:20,950 INFO  = [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] = (DefaultQuartzScheduler_Worker-7) Correlation ID: null, Call Stack: = null, Custom Event ID: -1, Message: VM Katello is down. Exit message: = User shut down from within the guest<o:p></o:p>2015-08-28 = 12:47:20,955 INFO  = [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] = (DefaultQuartzScheduler_Worker-7) VM Katello = (9013e3c2-3cd7-4eae-a3e6-f5e83a64db87) is running in db and not running = in VDS itsatltovirtaio.domain.local<o:p></o:p>2015-08-28 = 12:47:20,957 INFO  = [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] = (DefaultQuartzScheduler_Worker-7) START, FullListVdsCommand(HostName =3D = itsatltovirtaio.domain.local, HostId =3D = b783a2ee-4a63-46ca-9afc-b3b74f0e10ce, = vds=3DHost[itsatltovirtaio.domain.local,b783a2ee-4a63-46ca-9afc-b3b74f0e1= 0ce], vmIds=3D[9013e3c2-3cd7-4eae-a3e6-f5e83a64db87]), log id: = 39590448<o:p></o:p>2015-08-28 12:47:20,966 INFO  = [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] = (DefaultQuartzScheduler_Worker-7) FINISH, FullListVdsCommand, return: = [], log id: 39590448<o:p></o:p>2015-08-28 12:47:21,046 INFO  = [org.ovirt.engine.core.bll.ProcessDownVmCommand] = (org.ovirt.thread.pool-8-thread-17) [82bee5d] Running command: = ProcessDownVmCommand internal: true.<o:p></o:p>2015-08-28 = 12:47:24,589 INFO  = [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] = (DefaultQuartzScheduler_Worker-3) Polling and updating Async Tasks: 2 = tasks, 2 tasks to poll now<o:p></o:p>2015-08-28 = 12:47:24,600 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (DefaultQuartzScheduler_Worker-3) SPMAsyncTask::PollTask: Polling task = 037b2c85-68d2-4159-8310-91c472038b5b (Parent Command = ProcessOvfUpdateForStorageDomain, Parameters Type = org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned = status finished, result 'success'.<o:p></o:p>2015-08-28 = 12:47:24,603 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (DefaultQuartzScheduler_Worker-3) BaseAsyncTask::onTaskEndSuccess: Task = 037b2c85-68d2-4159-8310-91c472038b5b (Parent Command = ProcessOvfUpdateForStorageDomain, Parameters Type = org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended = successfully.<o:p></o:p>2015-08-28 12:47:24,604 INFO  = [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] = (DefaultQuartzScheduler_Worker-3) Task with DB Task ID = 0e6a6d72-0cea-41aa-8fe9-9262bc53d558 and VDSM Task ID = cd125365-3344-4f45-b67a-39c2fa5112ab is in state Polling. End action for = command e9edfed0-915a-4534-b774-c07682bafa59 will proceed when all the = entitys tasks are completed.<o:p></o:p>2015-08-28 = 12:47:24,605 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (DefaultQuartzScheduler_Worker-3) SPMAsyncTask::PollTask: Polling task = cd125365-3344-4f45-b67a-39c2fa5112ab (Parent Command = ProcessOvfUpdateForStorageDomain, Parameters Type = org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned = status finished, result 'success'.<o:p></o:p>2015-08-28 = 12:47:24,606 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (DefaultQuartzScheduler_Worker-3) BaseAsyncTask::onTaskEndSuccess: Task = cd125365-3344-4f45-b67a-39c2fa5112ab (Parent Command = ProcessOvfUpdateForStorageDomain, Parameters Type = org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended = successfully.<o:p></o:p>2015-08-28 12:47:24,606 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (DefaultQuartzScheduler_Worker-3) = CommandAsyncTask::endActionIfNecessary: All tasks of command = e9edfed0-915a-4534-b774-c07682bafa59 has ended -> executing = endAction<o:p></o:p>2015-08-28 12:47:24,607 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (DefaultQuartzScheduler_Worker-3) CommandAsyncTask::endAction: Ending = action for 2 tasks (command ID: e9edfed0-915a-4534-b774-c07682bafa59): = calling endAction .<o:p></o:p>2015-08-28 12:47:24,607 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (org.ovirt.thread.pool-8-thread-33) CommandAsyncTask::endCommandAction = [within thread] context: Attempting to endAction = ProcessOvfUpdateForStorageDomain, executionIndex: = 0<o:p></o:p>2015-08-28 12:47:24,668 INFO  = [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] = (org.ovirt.thread.pool-8-thread-33) [484cded8] Ending command = successfully: = org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand<o:p></o= :p>2015-08-28 12:47:24,669 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (org.ovirt.thread.pool-8-thread-33) [484cded8] = CommandAsyncTask::HandleEndActionResult [within thread]: endAction for = action type ProcessOvfUpdateForStorageDomain completed, handling the = result.<o:p></o:p>2015-08-28 12:47:24,670 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (org.ovirt.thread.pool-8-thread-33) [484cded8] = CommandAsyncTask::HandleEndActionResult [within thread]: endAction for = action type ProcessOvfUpdateForStorageDomain succeeded, clearing = tasks.<o:p></o:p>2015-08-28 12:47:24,671 INFO  = [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (org.ovirt.thread.pool-8-thread-33) [484cded8] = SPMAsyncTask::ClearAsyncTask: Attempting to clear task = cd125365-3344-4f45-b67a-39c2fa5112ab<o:p></o:p>2015-08-28 = 12:47:24,671 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-33) [484cded8] START, = SPMClearTaskVDSCommand( storagePoolId =3D = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit =3D false, = taskId =3D cd125365-3344-4f45-b67a-39c2fa5112ab), log id: = 73f5ca79<o:p></o:p>2015-08-28 12:47:24,700 INFO  = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-33) [484cded8] START, = HSMClearTaskVDSCommand(HostName =3D itsatltovirtaio.domain.local, HostId = =3D b783a2ee-4a63-46ca-9afc-b3b74f0e10ce, = taskId=3Dcd125365-3344-4f45-b67a-39c2fa5112ab), log id: = dc00969<o:p></o:p>2015-08-28 12:47:24,726 INFO  = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-33) [484cded8] FINISH, = HSMClearTaskVDSCommand, log id: dc00969<o:p></o:p>2015-08-28 = 12:47:24,728 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-33) [484cded8] FINISH, = SPMClearTaskVDSCommand, log id: 73f5ca79<o:p></o:p>2015-08-28 = 12:47:24,742 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (org.ovirt.thread.pool-8-thread-33) [484cded8] = BaseAsyncTask::removeTaskFromDB: Removed task = cd125365-3344-4f45-b67a-39c2fa5112ab from = DataBase<o:p></o:p>2015-08-28 12:47:24,744 INFO  = [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (org.ovirt.thread.pool-8-thread-33) [484cded8] = SPMAsyncTask::ClearAsyncTask: Attempting to clear task = 037b2c85-68d2-4159-8310-91c472038b5b<o:p></o:p>2015-08-28 = 12:47:24,745 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-33) [484cded8] START, = SPMClearTaskVDSCommand( storagePoolId =3D = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit =3D false, = taskId =3D 037b2c85-68d2-4159-8310-91c472038b5b), log id: = 3b75651d<o:p></o:p>2015-08-28 12:47:24,776 INFO  = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-33) [484cded8] START, = HSMClearTaskVDSCommand(HostName =3D itsatltovirtaio.domain.local, HostId = =3D b783a2ee-4a63-46ca-9afc-b3b74f0e10ce, = taskId=3D037b2c85-68d2-4159-8310-91c472038b5b), log id: = 1841716a<o:p></o:p>2015-08-28 12:47:24,793 INFO  = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-33) [484cded8] FINISH, = HSMClearTaskVDSCommand, log id: 1841716a<o:p></o:p>2015-08-28 = 12:47:24,795 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-33) [484cded8] FINISH, = SPMClearTaskVDSCommand, log id: 3b75651d<o:p></o:p>2015-08-28 = 12:47:24,808 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (org.ovirt.thread.pool-8-thread-33) [484cded8] = BaseAsyncTask::removeTaskFromDB: Removed task = 037b2c85-68d2-4159-8310-91c472038b5b from = DataBase<o:p></o:p>2015-08-28 12:47:24,810 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (org.ovirt.thread.pool-8-thread-33) [484cded8] = CommandAsyncTask::HandleEndActionResult [within thread]: Removing = CommandMultiAsyncTasks object for entity = e9edfed0-915a-4534-b774-c07682bafa59<o:p></o:p>2015-08-28 = 12:48:09,771 WARN  = [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] = (ajp--127.0.0.1-8702-9) [c1d5ad4] The message key CloneVm is missing = from bundles/ExecutionMessages<o:p></o:p>2015-08-28 = 12:48:09,966 INFO  [org.ovirt.engine.core.bll.CloneVmCommand] = (ajp--127.0.0.1-8702-9) [c1d5ad4] Lock Acquired to object EngineLock = [exclusiveLocks=3D key: Katello_2.3 value: = VM_NAME<o:p></o:p>, sharedLocks=3D key: = e7e99288-ad83-406e-9cb6-7a5aa443de9b value: DISK<o:p></o:p>key: = 6281b597-020d-4ea7-a954-bb798a0ca4f1 value: DISK<o:p></o:p>key: = 9013e3c2-3cd7-4eae-a3e6-f5e83a64db87 value: VM<o:p></o:p>]<o:p></o:p>2015-08-28 = 12:48:10,691 INFO  [org.ovirt.engine.core.bll.CloneVmCommand] = (ajp--127.0.0.1-8702-9) [c1d5ad4] Running command: CloneVmCommand = internal: false. Entities affected :  ID: = 9013e3c2-3cd7-4eae-a3e6-f5e83a64db87 Type: VMAction group CREATE_VM with = role type USER<o:p></o:p>2015-08-28 12:48:11,370 INFO  = [org.ovirt.engine.core.bll.CopyImageGroupCommand] = (ajp--127.0.0.1-8702-9) [7bfcbcdd] Running command: = CopyImageGroupCommand internal: true. Entities affected :  ID: = 937822d9-8a59-490f-95b7-48371ae32253 Type: = Storage<o:p></o:p>2015-08-28 12:48:11,529 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] = (ajp--127.0.0.1-8702-9) [7bfcbcdd] START, CopyImageVDSCommand( = storagePoolId =3D 00000002-0002-0002-0002-000000000021, = ignoreFailoverLimit =3D false, storageDomainId =3D = 937822d9-8a59-490f-95b7-48371ae32253, imageGroupId =3D = 6281b597-020d-4ea7-a954-bb798a0ca4f1, imageId =3D = 2a2015a1-f62c-4e32-8b04-77ece2ba4cc1, dstImageGroupId =3D = e64d7be5-7643-4ba1-b347-80c923f130e6, vmId =3D = ee2ea036-2af3-4a18-9329-08a7b0e7ce7c, dstImageId =3D = 6364bb72-8cb6-49c0-9673-f01ec49b88f2, imageDescription =3D , = dstStorageDomainId =3D 937822d9-8a59-490f-95b7-48371ae32253, = copyVolumeType =3D LeafVol, volumeFormat =3D RAW, preallocate =3D = Sparse, postZero =3D false, force =3D false), log id: = 3caf0bb2<o:p></o:p>2015-08-28 12:48:11,537 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] = (ajp--127.0.0.1-8702-9) [7bfcbcdd] -- executeIrsBrokerCommand: calling = 'copyImage' with two new parameters: description and = UUID<o:p></o:p>2015-08-28 12:48:11,539 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] = (ajp--127.0.0.1-8702-9) [7bfcbcdd] -- copyImage = parameters:<o:p></o:p>        &= nbsp;       = sdUUID=3D937822d9-8a59-490f-95b7-48371ae32253<o:p></o:p>        &= nbsp;       = spUUID=3D00000002-0002-0002-0002-000000000021<o:p></o:p>        &= nbsp;       = vmGUID=3Dee2ea036-2af3-4a18-9329-08a7b0e7ce7c<o:p></o:p>        &= nbsp;       = srcImageGUID=3D6281b597-020d-4ea7-a954-bb798a0ca4f1<o:p></o:p>=         &= nbsp;       = srcVolUUID=3D2a2015a1-f62c-4e32-8b04-77ece2ba4cc1<o:p></o:p><p= class=3DMsoNormal>        &= nbsp;       = dstImageGUID=3De64d7be5-7643-4ba1-b347-80c923f130e6<o:p></o:p>=         &= nbsp;       = dstVolUUID=3D6364bb72-8cb6-49c0-9673-f01ec49b88f2<o:p></o:p><p= class=3DMsoNormal>        &= nbsp;       = descr=3D<o:p></o:p>        &= nbsp;       = dstSdUUID=3D937822d9-8a59-490f-95b7-48371ae32253<o:p></o:p>2015-08-28 = 12:48:13,882 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] = (ajp--127.0.0.1-8702-9) [7bfcbcdd] FINISH, CopyImageVDSCommand, return: = 00000000-0000-0000-0000-000000000000, log id: = 3caf0bb2<o:p></o:p>2015-08-28 12:48:13,909 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (ajp--127.0.0.1-8702-9) [7bfcbcdd] CommandAsyncTask::Adding = CommandMultiAsyncTasks object for command = f81024b3-e534-4df4-907b-91e690bf049c<o:p></o:p>2015-08-28 = 12:48:13,912 INFO  = [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] = (ajp--127.0.0.1-8702-9) [7bfcbcdd] CommandMultiAsyncTasks::AttachTask: = Attaching task 11079859-f235-4227-b2ba-b51f88a0f9cd to command = f81024b3-e534-4df4-907b-91e690bf049c.<o:p></o:p>2015-08-28 = 12:48:13,941 INFO  = [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] = (ajp--127.0.0.1-8702-9) [7bfcbcdd] Adding task = 11079859-f235-4227-b2ba-b51f88a0f9cd (Parent Command CloneVm, Parameters = Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), = polling hasn't started yet..<o:p></o:p>2015-08-28 = 12:48:14,043 INFO  = [org.ovirt.engine.core.bll.CopyImageGroupCommand] = (ajp--127.0.0.1-8702-9) [89f7645] Running command: CopyImageGroupCommand = internal: true. Entities affected :  ID: = 937822d9-8a59-490f-95b7-48371ae32253 Type: = Storage<o:p></o:p>2015-08-28 12:48:14,083 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] = (ajp--127.0.0.1-8702-9) [89f7645] START, CopyImageVDSCommand( = storagePoolId =3D 00000002-0002-0002-0002-000000000021, = ignoreFailoverLimit =3D false, storageDomainId =3D = 937822d9-8a59-490f-95b7-48371ae32253, imageGroupId =3D = e7e99288-ad83-406e-9cb6-7a5aa443de9b, imageId =3D = c5762dec-d9d1-4842-84d1-05896d4d27fb, dstImageGroupId =3D = 7f5dd048-048f-49c1-9589-c935fcdccfdd, vmId =3D = ee2ea036-2af3-4a18-9329-08a7b0e7ce7c, dstImageId =3D = f57531cf-fb00-4a4d-9aad-c3ffa01f58ce, imageDescription =3D , = dstStorageDomainId =3D 937822d9-8a59-490f-95b7-48371ae32253, = copyVolumeType =3D LeafVol, volumeFormat =3D RAW, preallocate =3D = Sparse, postZero =3D false, force =3D false), log id: = 11b82b6e<o:p></o:p>2015-08-28 12:48:14,086 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] = (ajp--127.0.0.1-8702-9) [89f7645] -- executeIrsBrokerCommand: calling = 'copyImage' with two new parameters: description and = UUID<o:p></o:p>2015-08-28 12:48:14,087 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] = (ajp--127.0.0.1-8702-9) [89f7645] -- copyImage = parameters:<o:p></o:p>        &= nbsp;       = sdUUID=3D937822d9-8a59-490f-95b7-48371ae32253<o:p></o:p>        &= nbsp;       = spUUID=3D00000002-0002-0002-0002-000000000021<o:p></o:p>        &= nbsp;       = vmGUID=3Dee2ea036-2af3-4a18-9329-08a7b0e7ce7c<o:p></o:p>        &= nbsp;       = srcImageGUID=3De7e99288-ad83-406e-9cb6-7a5aa443de9b<o:p></o:p>=         &= nbsp;       = srcVolUUID=3Dc5762dec-d9d1-4842-84d1-05896d4d27fb<o:p></o:p><p= class=3DMsoNormal>        &= nbsp;       = dstImageGUID=3D7f5dd048-048f-49c1-9589-c935fcdccfdd<o:p></o:p>=         &= nbsp;       = dstVolUUID=3Df57531cf-fb00-4a4d-9aad-c3ffa01f58ce<o:p></o:p><p= class=3DMsoNormal>        &= nbsp;       = descr=3D<o:p></o:p>        &= nbsp;       = dstSdUUID=3D937822d9-8a59-490f-95b7-48371ae32253<o:p></o:p>2015-08-28 = 12:48:17,722 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] = (ajp--127.0.0.1-8702-9) [89f7645] FINISH, CopyImageVDSCommand, return: = 00000000-0000-0000-0000-000000000000, log id: = 11b82b6e<o:p></o:p>2015-08-28 12:48:17,753 INFO  = [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] = (ajp--127.0.0.1-8702-9) [89f7645] CommandMultiAsyncTasks::AttachTask: = Attaching task a961301c-0da0-49b6-a2e7-787de9ea8fe6 to command = f81024b3-e534-4df4-907b-91e690bf049c.<o:p></o:p>2015-08-28 = 12:48:17,785 INFO  = [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] = (ajp--127.0.0.1-8702-9) [89f7645] Adding task = a961301c-0da0-49b6-a2e7-787de9ea8fe6 (Parent Command CloneVm, Parameters = Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), = polling hasn't started yet..<o:p></o:p>2015-08-28 = 12:48:18,136 INFO  = [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] = (ajp--127.0.0.1-8702-9) [89f7645] Correlation ID: c1d5ad4, Job ID: = e36f06d1-3c60-4b4c-86db-0ba5f903b254, Call Stack: null, Custom Event ID: = -1, Message: VM Katello_2.3 creation was initiated by = admin@internal.<o:p></o:p>2015-08-28 12:48:18,138 INFO  = [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (ajp--127.0.0.1-8702-9) = [89f7645] BaseAsyncTask::startPollingTask: Starting to poll task = 11079859-f235-4227-b2ba-b51f88a0f9cd.<o:p></o:p>2015-08-28 = 12:48:18,140 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (ajp--127.0.0.1-8702-9) [89f7645] BaseAsyncTask::startPollingTask: = Starting to poll task = a961301c-0da0-49b6-a2e7-787de9ea8fe6.<o:p></o:p>2015-08-28 = 12:48:24,607 INFO  = [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] = (DefaultQuartzScheduler_Worker-61) Polling and updating Async Tasks: 4 = tasks, 2 tasks to poll now<o:p></o:p>2015-08-28 = 12:48:24,621 ERROR = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSComma= nd] (DefaultQuartzScheduler_Worker-61) Failed in = HSMGetAllTasksStatusesVDS method<o:p></o:p>2015-08-28 = 12:48:24,622 ERROR = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSComma= nd] (DefaultQuartzScheduler_Worker-61) Failed in = HSMGetAllTasksStatusesVDS method<o:p></o:p>2015-08-28 = 12:48:24,624 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (DefaultQuartzScheduler_Worker-61) SPMAsyncTask::PollTask: Polling task = a961301c-0da0-49b6-a2e7-787de9ea8fe6 (Parent Command CloneVm, Parameters = Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) = returned status finished, result 'cleanSuccess'.<o:p></o:p>2015-08-28 = 12:48:24,635 ERROR [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (DefaultQuartzScheduler_Worker-61) BaseAsyncTask::logEndTaskFailure: = Task a961301c-0da0-49b6-a2e7-787de9ea8fe6 (Parent Command CloneVm, = Parameters Type = org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended with = failure:<o:p></o:p>-- Result: cleanSuccess<o:p></o:p>-- Message: = VDSGenericException: VDSErrorException: Failed to = HSMGetAllTasksStatusesVDS, error =3D Volume does not exist, code =3D = 201,<o:p></o:p>-- Exception: VDSGenericException: = VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error =3D Volume = does not exist, code =3D 201<o:p></o:p>2015-08-28 = 12:48:24,640 INFO  = [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] = (DefaultQuartzScheduler_Worker-61) Task with DB Task ID = ed763e40-d1e9-4c36-8822-e6b232a864b0 and VDSM Task ID = 11079859-f235-4227-b2ba-b51f88a0f9cd is in state Polling. End action for = command f81024b3-e534-4df4-907b-91e690bf049c will proceed when all the = entitys tasks are completed.<o:p></o:p>2015-08-28 = 12:48:24,643 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (DefaultQuartzScheduler_Worker-61) SPMAsyncTask::PollTask: Polling task = 11079859-f235-4227-b2ba-b51f88a0f9cd (Parent Command CloneVm, Parameters = Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) = returned status finished, result 'cleanSuccess'.<o:p></o:p>2015-08-28 = 12:48:24,652 ERROR [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (DefaultQuartzScheduler_Worker-61) BaseAsyncTask::logEndTaskFailure: = Task 11079859-f235-4227-b2ba-b51f88a0f9cd (Parent Command CloneVm, = Parameters Type = org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended with = failure:<o:p></o:p>-- Result: cleanSuccess<o:p></o:p>-- Message: = VDSGenericException: VDSErrorException: Failed to = HSMGetAllTasksStatusesVDS, error =3D Volume does not exist, code =3D = 201,<o:p></o:p>-- Exception: VDSGenericException: = VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error =3D Volume = does not exist, code =3D 201<o:p></o:p>2015-08-28 = 12:48:24,659 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (DefaultQuartzScheduler_Worker-61) = CommandAsyncTask::endActionIfNecessary: All tasks of command = f81024b3-e534-4df4-907b-91e690bf049c has ended -> executing = endAction<o:p></o:p>2015-08-28 12:48:24,662 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (DefaultQuartzScheduler_Worker-61) CommandAsyncTask::endAction: Ending = action for 2 tasks (command ID: f81024b3-e534-4df4-907b-91e690bf049c): = calling endAction .<o:p></o:p>2015-08-28 12:48:24,664 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (org.ovirt.thread.pool-8-thread-2) CommandAsyncTask::endCommandAction = [within thread] context: Attempting to endAction CloneVm, = executionIndex: 0<o:p></o:p>2015-08-28 12:48:24,827 ERROR = [org.ovirt.engine.core.bll.CloneVmCommand] = (org.ovirt.thread.pool-8-thread-2) [c1d5ad4] Ending command with = failure: = org.ovirt.engine.core.bll.CloneVmCommand<o:p></o:p>2015-08-28 = 12:48:24,844 ERROR [org.ovirt.engine.core.bll.CopyImageGroupCommand] = (org.ovirt.thread.pool-8-thread-2) [89f7645] Ending command with = failure: = org.ovirt.engine.core.bll.CopyImageGroupCommand<o:p></o:p>2015-08-28 = 12:48:24,853 INFO  [org.ovirt.engine.core.bll.RemoveImageCommand] = (org.ovirt.thread.pool-8-thread-2) [779578db] Running command: = RemoveImageCommand internal: true. Entities affected :  ID: = 937822d9-8a59-490f-95b7-48371ae32253 Type: = Storage<o:p></o:p>2015-08-28 12:48:24,913 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] = (org.ovirt.thread.pool-8-thread-2) [779578db] START, = DeleteImageGroupVDSCommand( storagePoolId =3D = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit =3D false, = storageDomainId =3D 937822d9-8a59-490f-95b7-48371ae32253, imageGroupId = =3D 7f5dd048-048f-49c1-9589-c935fcdccfdd, postZeros =3D false, = forceDelete =3D false), log id: 6bc5ac77<o:p></o:p>2015-08-28 = 12:48:24,979 ERROR = [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] = (org.ovirt.thread.pool-8-thread-2) [779578db] Command = DeleteImageGroupVDSCommand( storagePoolId =3D = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit =3D false, = storageDomainId =3D 937822d9-8a59-490f-95b7-48371ae32253, imageGroupId = =3D 7f5dd048-048f-49c1-9589-c935fcdccfdd, postZeros =3D false, = forceDelete =3D false) execution failed. Exception: = IrsOperationFailedNoFailoverException: IRSGenericException: = IRSErrorException: Image does not exist in domain: = u'image=3D7f5dd048-048f-49c1-9589-c935fcdccfdd, = domain=3D937822d9-8a59-490f-95b7-48371ae32253'<o:p></o:p>2015-08-28 = 12:48:24,984 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] = (org.ovirt.thread.pool-8-thread-2) [779578db] FINISH, = DeleteImageGroupVDSCommand, log id: 6bc5ac77<o:p></o:p>2015-08-28 = 12:48:24,986 INFO  [org.ovirt.engine.core.bll.RemoveImageCommand] = (org.ovirt.thread.pool-8-thread-2) [779578db] Disk = 7f5dd048-048f-49c1-9589-c935fcdccfdd doesnt exist on storage domain = 937822d9-8a59-490f-95b7-48371ae32253, rolling = forward<o:p></o:p>2015-08-28 12:48:25,126 INFO  = [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] = (org.ovirt.thread.pool-8-thread-2) [779578db] Removed task = da4b9e1f-43f3-4de0-8f38-bdc5bc8ebaae from = DataBase<o:p></o:p>2015-08-28 12:48:25,144 ERROR = [org.ovirt.engine.core.bll.CopyImageGroupCommand] = (org.ovirt.thread.pool-8-thread-2) [7bfcbcdd] Ending command with = failure: = org.ovirt.engine.core.bll.CopyImageGroupCommand<o:p></o:p>2015-08-28 = 12:48:25,152 INFO  [org.ovirt.engine.core.bll.RemoveImageCommand] = (org.ovirt.thread.pool-8-thread-2) [2a0d85fe] Running command: = RemoveImageCommand internal: true. Entities affected :  ID: = 937822d9-8a59-490f-95b7-48371ae32253 Type: = Storage<o:p></o:p>2015-08-28 12:48:25,203 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] = (org.ovirt.thread.pool-8-thread-2) [2a0d85fe] START, = DeleteImageGroupVDSCommand( storagePoolId =3D = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit =3D false, = storageDomainId =3D 937822d9-8a59-490f-95b7-48371ae32253, imageGroupId = =3D e64d7be5-7643-4ba1-b347-80c923f130e6, postZeros =3D false, = forceDelete =3D false), log id: ff00a91<o:p></o:p>2015-08-28 = 12:48:25,283 ERROR = [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] = (org.ovirt.thread.pool-8-thread-2) [2a0d85fe] Command = DeleteImageGroupVDSCommand( storagePoolId =3D = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit =3D false, = storageDomainId =3D 937822d9-8a59-490f-95b7-48371ae32253, imageGroupId = =3D e64d7be5-7643-4ba1-b347-80c923f130e6, postZeros =3D false, = forceDelete =3D false) execution failed. Exception: = IrsOperationFailedNoFailoverException: IRSGenericException: = IRSErrorException: Image does not exist in domain: = u'image=3De64d7be5-7643-4ba1-b347-80c923f130e6, = domain=3D937822d9-8a59-490f-95b7-48371ae32253'<o:p></o:p>2015-08-28 = 12:48:25,290 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] = (org.ovirt.thread.pool-8-thread-2) [2a0d85fe] FINISH, = DeleteImageGroupVDSCommand, log id: ff00a91<o:p></o:p>2015-08-28 = 12:48:25,292 INFO  [org.ovirt.engine.core.bll.RemoveImageCommand] = (org.ovirt.thread.pool-8-thread-2) [2a0d85fe] Disk = e64d7be5-7643-4ba1-b347-80c923f130e6 doesnt exist on storage domain = 937822d9-8a59-490f-95b7-48371ae32253, rolling = forward<o:p></o:p>2015-08-28 12:48:25,406 INFO  = [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] = (org.ovirt.thread.pool-8-thread-2) [2a0d85fe] Removed task = 8ad7c433-a77f-4003-a11e-f061ca6cf844 from = DataBase<o:p></o:p>2015-08-28 12:48:25,613 INFO  = [org.ovirt.engine.core.bll.CloneVmCommand] = (org.ovirt.thread.pool-8-thread-2) Lock freed to object EngineLock = [exclusiveLocks=3D key: Katello_2.3 value: = VM_NAME<o:p></o:p>, sharedLocks=3D key: = e7e99288-ad83-406e-9cb6-7a5aa443de9b value: DISK<o:p></o:p>key: = 6281b597-020d-4ea7-a954-bb798a0ca4f1 value: DISK<o:p></o:p>key: = 9013e3c2-3cd7-4eae-a3e6-f5e83a64db87 value: VM<o:p></o:p>]<o:p></o:p>2015-08-28 = 12:48:25,676 ERROR = [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogableBase] = (org.ovirt.thread.pool-8-thread-2) Error during log command: = org.ovirt.engine.core.bll.CloneVmCommand. Exception = java.lang.NullPointerException: = java.lang.NullPointerException<o:p></o:p>        at = org.ovirt.engine.core.bll.CloneVmCommand.getVm(CloneVmCommand.java:140) = [bll.jar:]<o:p></o:p>        at = org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogableBase.getV= ds(AuditLogableBase.java:353) [dal.jar:]<o:p></o:p>        at = org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogableBase.getV= dsIdRef(AuditLogableBase.java:237) [dal.jar:]<o:p></o:p>        at = org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector.save= ToDb(AuditLogDirector.java:140) [dal.jar:]<o:p></o:p>        at = org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector.log(= AuditLogDirector.java:107) [dal.jar:]<o:p></o:p>        at = org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector.log(= AuditLogDirector.java:100) [dal.jar:]<o:p></o:p>        at = org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector.log(= AuditLogDirector.java:96) [dal.jar:]<o:p></o:p>        at = org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogableBase.log(= AuditLogableBase.java:459) [dal.jar:]<o:p></o:p>        at = org.ovirt.engine.core.bll.CommandBase.logCommand(CommandBase.java:1447) = [bll.jar:]<o:p></o:p>        at = org.ovirt.engine.core.bll.CommandBase.endAction(CommandBase.java:513) = [bll.jar:]<o:p></o:p>        at = org.ovirt.engine.core.bll.tasks.DecoratedCommand.endAction(DecoratedComma= nd.java:17) [bll.jar:]<o:p></o:p>        at = org.ovirt.engine.core.bll.tasks.CoCoAsyncTaskHelper.endAction(CoCoAsyncTa= skHelper.java:337) [bll.jar:]<o:p></o:p>        at = org.ovirt.engine.core.bll.tasks.CommandCoordinatorImpl.endAction(CommandC= oordinatorImpl.java:383) [bll.jar:]<o:p></o:p>        at = org.ovirt.engine.core.bll.tasks.CommandAsyncTask.endCommandAction(Command= AsyncTask.java:149) [bll.jar:]<o:p></o:p>        at = org.ovirt.engine.core.bll.tasks.CommandAsyncTask.access$000(CommandAsyncT= ask.java:28) [bll.jar:]<o:p></o:p>        at = org.ovirt.engine.core.bll.tasks.CommandAsyncTask$1.run(CommandAsyncTask.j= ava:109) [bll.jar:]<o:p></o:p>        at = org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunn= able.run(ThreadPoolUtil.java:90) [utils.jar:]<o:p></o:p>        at = java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) = [rt.jar:1.7.0_79]<o:p></o:p>        at = java.util.concurrent.FutureTask.run(FutureTask.java:262) = [rt.jar:1.7.0_79]<o:p></o:p>        at = java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java= :1145) [rt.jar:1.7.0_79]<o:p></o:p>        at = java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.jav= a:615) [rt.jar:1.7.0_79]<o:p></o:p>        at = java.lang.Thread.run(Thread.java:745) = [rt.jar:1.7.0_79]<o:p></o:p><o:p> </o:p>2015-08-28 = 12:48:25,700 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (org.ovirt.thread.pool-8-thread-2) = CommandAsyncTask::HandleEndActionResult [within thread]: endAction for = action type CloneVm completed, handling the = result.<o:p></o:p>2015-08-28 12:48:25,703 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (org.ovirt.thread.pool-8-thread-2) = CommandAsyncTask::HandleEndActionResult [within thread]: endAction for = action type CloneVm succeeded, clearing tasks.<o:p></o:p>2015-08-28 = 12:48:25,714 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (org.ovirt.thread.pool-8-thread-2) SPMAsyncTask::ClearAsyncTask: = Attempting to clear task = a961301c-0da0-49b6-a2e7-787de9ea8fe6<o:p></o:p>2015-08-28 = 12:48:25,715 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-2) START, SPMClearTaskVDSCommand( = storagePoolId =3D 00000002-0002-0002-0002-000000000021, = ignoreFailoverLimit =3D false, taskId =3D = a961301c-0da0-49b6-a2e7-787de9ea8fe6), log id: = 4852f196<o:p></o:p>2015-08-28 12:48:25,745 INFO  = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-2) START, = HSMClearTaskVDSCommand(HostName =3D itsatltovirtaio.domain.local, HostId = =3D b783a2ee-4a63-46ca-9afc-b3b74f0e10ce, = taskId=3Da961301c-0da0-49b6-a2e7-787de9ea8fe6), log id: = 3d72d3f2<o:p></o:p>2015-08-28 12:48:25,766 INFO  = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-2) FINISH, HSMClearTaskVDSCommand, log = id: 3d72d3f2<o:p></o:p>2015-08-28 12:48:25,767 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-2) FINISH, SPMClearTaskVDSCommand, log = id: 4852f196<o:p></o:p>2015-08-28 12:48:25,780 INFO  = [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (org.ovirt.thread.pool-8-thread-2) BaseAsyncTask::removeTaskFromDB: = Removed task a961301c-0da0-49b6-a2e7-787de9ea8fe6 from = DataBase<o:p></o:p>2015-08-28 12:48:25,782 INFO  = [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (org.ovirt.thread.pool-8-thread-2) SPMAsyncTask::ClearAsyncTask: = Attempting to clear task = 11079859-f235-4227-b2ba-b51f88a0f9cd<o:p></o:p>2015-08-28 = 12:48:25,784 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-2) START, SPMClearTaskVDSCommand( = storagePoolId =3D 00000002-0002-0002-0002-000000000021, = ignoreFailoverLimit =3D false, taskId =3D = 11079859-f235-4227-b2ba-b51f88a0f9cd), log id: = 13acb232<o:p></o:p>2015-08-28 12:48:25,821 INFO  = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-2) START, = HSMClearTaskVDSCommand(HostName =3D itsatltovirtaio.domain.local, HostId = =3D b783a2ee-4a63-46ca-9afc-b3b74f0e10ce, = taskId=3D11079859-f235-4227-b2ba-b51f88a0f9cd), log id: = 2ee6fab5<o:p></o:p>2015-08-28 12:48:25,842 INFO  = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-2) FINISH, HSMClearTaskVDSCommand, log = id: 2ee6fab5<o:p></o:p>2015-08-28 12:48:25,844 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-2) FINISH, SPMClearTaskVDSCommand, log = id: 13acb232<o:p></o:p>2015-08-28 12:48:25,855 INFO  = [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (org.ovirt.thread.pool-8-thread-2) BaseAsyncTask::removeTaskFromDB: = Removed task 11079859-f235-4227-b2ba-b51f88a0f9cd from = DataBase<o:p></o:p>2015-08-28 12:48:25,857 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (org.ovirt.thread.pool-8-thread-2) = CommandAsyncTask::HandleEndActionResult [within thread]: Removing = CommandMultiAsyncTasks object for entity = f81024b3-e534-4df4-907b-91e690bf049c<o:p></o:p>2015-08-28 = 12:48:41,695 INFO  = [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] = (DefaultQuartzScheduler_Worker-99) Setting new tasks map. The map = contains now 2 tasks<o:p></o:p>2015-08-28 12:49:41,697 INFO  = [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] = (DefaultQuartzScheduler_Worker-49) Setting new tasks map. The map = contains now 0 tasks<o:p></o:p>2015-08-28 12:49:41,697 INFO  = [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] = (DefaultQuartzScheduler_Worker-49) Cleared all tasks of pool = 00000002-0002-0002-0002-000000000021.<o:p></o:p></div></body><= /html> ------=_NextPart_000_0183_01D0E19A.D0BB8DB0--

2 2

Cannot add Posix Storage
by Steve Kilduff 31 Aug '15

31 Aug '15

Hi guys, trying to reply to a previous topic but I am certain I am not doing it correct, regardless, here we go. moosefs mounting on ovirt. I am having similar problem to the person in the mail Cannot add Posix Storage. This is an ovirt setup attempt with moosefs. Mount options are: Path: mfsmount VFS type: fuse Mount opt: mfsmaster=mfsmaster,mfsport=9421,mfssubfolder=/ovirt,_netdev I specified a subfolder which may not exist on your mfs storae. In my setup, ovirt mounts the actual mfs mount without problem, the mount remains mounted afterwards as ovirt does not seem to unmount it successfully. I think the problem is the mount detection mechanism in python, It is looking for a pattern to match both the source/destination of the mount and failing: I hope this shows what I mean: vsdm.log output: OSError: [Errno 2] Mount of `mfsmount` at `/rhev/data-center/mnt/mfsmount` does not exist centos7 output of mount command: mfsmaster:9421 on /rhev/data-center/mnt/mfsmount type fuse.mfs (rw,relatime,user_id=0,group_id=0,allow_other) Source = mfsmount in first error out line, and source = ''mfsmaster:9421' on the OS, and I guess mount.py is trying to pattern match the mount and doesnt compare them correctly and bails out. I guess normally the source of the mount does not change name, like with the specifics I gave to mount mfs. I am trying to poke around the python to see if I can simply ignore this check, but my python skills are non existent. Also, as a workaround, I tried to first mount mfs without ovirt, and then get ovirt to bind mount my /mnt/moosefs how-ever in centos7 bind mount names are strange, I think broken when compared to centos6, the output of mount in centos7 when bind mounting /mnt/moosefs to /ovirt: mfsmaster:9421 on /mnt/moosfs mfsmaster:9421 on /ovirt where as in centos6 it would be: mfsmaster:9421 on /mnt/moosfs /mnt/moosfs on /ovirt Again, I think the pattern matching of mount.py is what fails during bind mount also. I think if bind mounting worked, the user could stick what-ever filesystem they wanted onto the OS, and get ovirt to bind mount it. Then it's the user's problem how to manage their FS. Best, Steve Kilduff

2 2

Automatically start VM after host reboot
by gregor 30 Aug '15

30 Aug '15

Hi, I installed oVirt allinone, is there a way to automatically start certain VM's after the host reboots? cheers gregor

2 1

Moving HostedEngine from one Cluster to another
by Groten, Ryan 30 Aug '15

30 Aug '15

--_000_e417a3e2209d46adb3c78d9208b76013CD1001M21corpads_ Content-Type: text/plain; charset="us-ascii" Content-Transfer-Encoding: quoted-printable My HostedEngine exists in the Default cluster, but since I'm upgrading my h= osts to RHEL7 I created a new Cluster and migrated all the VMs to it (inclu= ding HostedEngine). However in the GUI VM Tab HostedEngine still appears a= s in the Default Cluster. Because of this I can't remove this cluster (it = thinks there's a VM in it) even though there are no more hosts or VMs in it= . I also can't change the cluster of HostedEngine, it says "Cannot edit VM Cl= uster. This VM is not managed by the engine". Thanks, Ryan --_000_e417a3e2209d46adb3c78d9208b76013CD1001M21corpads_ Content-Type: text/html; charset="us-ascii" Content-Transfer-Encoding: quoted-printable <html xmlns:v=3D"urn:schemas-microsoft-com:vml" xmlns:o=3D"urn:schemas-micr= osoft-com:office:office" xmlns:w=3D"urn:schemas-microsoft-com:office:word" = xmlns:m=3D"http://schemas.microsoft.com/office/2004/12/omml" xmlns=3D"http:= //www.w3.org/TR/REC-html40"> <head> <meta http-equiv=3D"Content-Type" content=3D"text/html; charset=3Dus-ascii"= > <meta name=3D"Generator" content=3D"Microsoft Word 14 (filtered medium)"> <style></style> </head> <body lang=3D"EN-US" link=3D"blue" vlink=3D"purple"> <div class=3D"WordSection1"> My HostedEngine exists in the De= fault cluster, but since I’m upgrading my hosts to RHEL7 I created a = new Cluster and migrated all the VMs to it (including HostedEngine).  However in the GUI VM Tab HostedEngine still appears as in the Default Clu= ster.  Because of this I can’t remove this cluster (it thinks th= ere’s a VM in it) even though there are no more hosts or VMs in it.<o= :p></o:p> <o:p> </o:p> I also can’t change the cl= uster of HostedEngine, it says “Cannot edit VM Cluster.  This VM= is not managed by the engine”.<o:p></o:p> <o:p> </o:p> Thanks,<o:p></o:p> Ryan<o:p></o:p> </div> </body> </html> --_000_e417a3e2209d46adb3c78d9208b76013CD1001M21corpads_--

2 1

Storage domain / new harddisk overwrites existing harddisk
by Bernhard Krieger 30 Aug '15

30 Aug '15

2 1

Windows 2012 R2 template deploy unable to login
by Ian Fraser 30 Aug '15

30 Aug '15

Hi, I am failing to get a Windows Server 2012 R2 Template deploy successfully. It seems to go through the motions but I cannot log in with the password given in the initial run. It seems that something is failing in the sysprep part. The ovirt docs only mention Win 7/2008. Has anyone had any success in doing this? Best regards Ian Fraser Systems Administrator | Agency Sector Management (UK) Limited | www.asm.org.uk [t] +44 (0)1784 242200 | [f] +44 (0)1784 242012 | [e] ian.fraser(a)asm.org.uk Ashford House 41-45 Church Road Ashford Middx TW15 2TQ Follow us on twitter @asmukltd ________________________________ The information in this message and any attachment is intended for the addressee and is confidential. If you are not that addressee, no action should be taken in reliance on the information and you should please reply to this message immediately to inform us of incorrect receipt and destroy this message and any attachments. For the purposes of internet level email security incoming and outgoing emails may be read by personnel other than the named recipient or sender. Whilst all reasonable efforts are made, ASM (UK) Ltd cannot guarantee that emails and attachments are virus free or compatible with your systems. You should make your own checks and ASM (UK) Ltd does not accept liability in respect of viruses or computer problems experienced. Registered address: Agency Sector Management (UK) Ltd. Ashford House, 41-45 Church Road, Ashford, Middlesex, TW15 2TQ Registered in England No.2053849 ______________________________________________________________________ This email has been scanned by the Symantec Email Security.cloud service. For more information please visit http://www.symanteccloud.com ______________________________________________________________________

5 9

Stuck at "Enrolling serial console certificate"
by Sahina Bose 29 Aug '15

29 Aug '15

Hi all, While installing a host to ovirt-3.6 engine, the host installation is stuck at "Enrolling serial console certificate" I installed the engine from ovirt-release36, and answered No to setting up WebConsole-proxy as well as VM Console proxy on the engine. Does anyone know how to debug this? thanks sahina

4 10

Delete snapshot with status illegal - live merge not possible
by Jan Siml 28 Aug '15

28 Aug '15

Hello, after a failed live storage migration (cause unknown) we have a snapshot which is undeletable due to its status 'illegal' (as seen in storage/snapshot tab). I have already found some bugs [1],[2],[3] regarding this issue, but no way how to solve the issue within oVirt 3.5.3. I have attached the relevant engine.log snippet. Is there any way to do a live merge (and therefore delete the snapshot)? [1] https://bugzilla.redhat.com/show_bug.cgi?id=1213157 [2] https://bugzilla.redhat.com/show_bug.cgi?id=1247377 links to [3] [3] https://bugzilla.redhat.com/show_bug.cgi?id=1247379 (no access) Kind regards Jan Siml

3 10

Ovirt/Gluster
by Sander Hoentjen 28 Aug '15

28 Aug '15

Hi, We are looking for some easy to manage self contained VM hosting. Ovirt with GlusterFS seems to fit that bill perfectly. I installed it and then starting kicking the tires. First results looked promising, but now I can get a VM to pause indefinitely fairly easy: My setup is 3 hosts that are in a Virt and Gluster cluster. Gluster is setup as replica-3. The gluster export is used as the storage domain for the VM's. Now when I start the VM all is good, performance is good enough so we are happy. I then start bonnie++ to generate some load. I have a VM running on host 1, host 2 is SPM and all 3 VM's are seeing some network traffic courtesy of gluster. Now, for fun, suddenly the network on host3 goes bad (iptables -I OUTPUT -m statistic --mode random --probability 0.75 -j REJECT). Some time later I see the guest has a small "hickup", I'm guessing that is when gluster decides host 3 is not allowed to play anymore. No big deal anyway. After a while 25% of packages just isn't good enough for Ovirt anymore, so the host will be fenced. After a reboot *sometimes* the VM will be paused, and even after the gluster self-heal is complete it can not be unpaused, has to be restarted. Is there anything I can do to prevent the VM from being paused? Regards, Sander

3 11

oVirt 3.5.3.1 - Snapshot Failure with Error creating a new volume, code = 205
by Christian Rebel 28 Aug '15

28 Aug '15

This is a multipart message in MIME format. ------=_NextPart_000_0197_01D0E1A8.8C2F9BB0 Content-Type: text/plain; charset="us-ascii" Content-Transfer-Encoding: 7bit Hi all, I have a problem to perform a Snapshot on one of my important VMs, can anyone please be so kind and assist me. #### start of problematic vm snapshot #### 2015-08-28 15:36:08,172 INFO [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (ajp--127.0.0.1-8702-6) [439efb74] Lock Acquired to object EngineLock [exclusiveLocks= key: ee2ea036-2af3-4a18-9329-08a7b0e7ce7c value: VM , sharedLocks= ] 2015-08-28 15:36:08,224 INFO [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (org.ovirt.thread.pool-8-thread-45) Command 44173a42-970f-42b3-8d09-ca113c58b5df persisting async task placeholder for child command be3c1922-aca6-4caf-a432-34fe95043446 2015-08-28 15:36:08,367 INFO [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (org.ovirt.thread.pool-8-thread-45) Command 44173a42-970f-42b3-8d09-ca113c58b5df persisting async task placeholder for child command 03a43c66-0fed-4a82-9139-7b89328f4ae4 2015-08-28 15:36:08,517 INFO [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (org.ovirt.thread.pool-8-thread-45) Running command: CreateAllSnapshotsFromVmCommand internal: false. Entities affected : ID: ee2ea036-2af3-4a18-9329-08a7b0e7ce7c Type: VMAction group MANIPULATE_VM_SNAPSHOTS with role type USER 2015-08-28 15:36:08,550 INFO [org.ovirt.engine.core.bll.CreateSnapshotCommand] (org.ovirt.thread.pool-8-thread-45) [86e8aad] Running command: CreateSnapshotCommand internal: true. Entities affected : ID: 00000000-0000-0000-0000-000000000000 Type: Storage 2015-08-28 15:36:08,560 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (org.ovirt.thread.pool-8-thread-45) [86e8aad] START, CreateSnapshotVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit = false, storageDomainId = 937822d9-8a59-490f-95b7-48371ae32253, imageGroupId = e7e99288-ad83-406e-9cb6-7a5aa443de9b, imageSizeInBytes = 21474836480, volumeFormat = COW, newImageId = 2013aa82-6316-4b54-851b-88bf7f523b9c, newImageDescription = , imageId = c5762dec-d9d1-4842-84d1-05896d4d27fb, sourceImageGroupId = e7e99288-ad83-406e-9cb6-7a5aa443de9b), log id: d1ffce0 2015-08-28 15:36:08,567 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (org.ovirt.thread.pool-8-thread-45) [86e8aad] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID 2015-08-28 15:36:08,655 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (org.ovirt.thread.pool-8-thread-45) [86e8aad] FINISH, CreateSnapshotVDSCommand, return: 2013aa82-6316-4b54-851b-88bf7f523b9c, log id: d1ffce0 2015-08-28 15:36:08,668 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-45) [86e8aad] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 44173a42-970f-42b3-8d09-ca113c58b5df 2015-08-28 15:36:08,670 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (org.ovirt.thread.pool-8-thread-45) [86e8aad] CommandMultiAsyncTasks::AttachTask: Attaching task 0221e559-0eec-468b-bc4f-a7aaa487661a to command 44173a42-970f-42b3-8d09-ca113c58b5df. 2015-08-28 15:36:08,734 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-8-thread-45) [86e8aad] Adding task 0221e559-0eec-468b-bc4f-a7aaa487661a (Parent Command CreateAllSnapshotsFromVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. 2015-08-28 15:36:08,793 INFO [org.ovirt.engine.core.bll.CreateSnapshotCommand] (org.ovirt.thread.pool-8-thread-45) [53768935] Running command: CreateSnapshotCommand internal: true. Entities affected : ID: 00000000-0000-0000-0000-000000000000 Type: Storage 2015-08-28 15:36:08,797 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (org.ovirt.thread.pool-8-thread-45) [53768935] START, CreateSnapshotVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit = false, storageDomainId = 937822d9-8a59-490f-95b7-48371ae32253, imageGroupId = 6281b597-020d-4ea7-a954-bb798a0ca4f1, imageSizeInBytes = 161061273600, volumeFormat = COW, newImageId = fd9c6e36-90ca-488a-8cbd-534a0caf6886, newImageDescription = , imageId = 2a2015a1-f62c-4e32-8b04-77ece2ba4cc1, sourceImageGroupId = 6281b597-020d-4ea7-a954-bb798a0ca4f1), log id: 4bc33f2e 2015-08-28 15:36:08,799 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (org.ovirt.thread.pool-8-thread-45) [53768935] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID 2015-08-28 15:36:09,000 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (org.ovirt.thread.pool-8-thread-45) [53768935] FINISH, CreateSnapshotVDSCommand, return: fd9c6e36-90ca-488a-8cbd-534a0caf6886, log id: 4bc33f2e 2015-08-28 15:36:09,011 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (org.ovirt.thread.pool-8-thread-45) [53768935] CommandMultiAsyncTasks::AttachTask: Attaching task c502e058-4b72-4d7f-9c97-a264866289e2 to command 44173a42-970f-42b3-8d09-ca113c58b5df. 2015-08-28 15:36:09,076 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-8-thread-45) [53768935] Adding task c502e058-4b72-4d7f-9c97-a264866289e2 (Parent Command CreateAllSnapshotsFromVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. 2015-08-28 15:36:09,235 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-45) Correlation ID: 439efb74, Job ID: 760f7564-fb7f-4bec-8ef7-5a1d3b7651fc, Call Stack: null, Custom Event ID: -1, Message: Snapshot 'before upg' creation for VM 'Katello_2.2' was initiated by admin@internal. 2015-08-28 15:36:09,237 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-45) BaseAsyncTask::startPollingTask: Starting to poll task 0221e559-0eec-468b-bc4f-a7aaa487661a. 2015-08-28 15:36:09,237 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-45) BaseAsyncTask::startPollingTask: Starting to poll task c502e058-4b72-4d7f-9c97-a264866289e2. 2015-08-28 15:36:11,279 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-80) Polling and updating Async Tasks: 2 tasks, 2 tasks to poll now 2015-08-28 15:36:11,309 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSCommand] (DefaultQuartzScheduler_Worker-80) Failed in HSMGetAllTasksStatusesVDS method 2015-08-28 15:36:11,311 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-80) SPMAsyncTask::PollTask: Polling task 0221e559-0eec-468b-bc4f-a7aaa487661a (Parent Command CreateAllSnapshotsFromVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'cleanFailure'. 2015-08-28 15:36:11,343 ERROR [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-80) BaseAsyncTask::logEndTaskFailure: Task 0221e559-0eec-468b-bc4f-a7aaa487661a (Parent Command CreateAllSnapshotsFromVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended with failure: -- Result: cleanFailure -- Message: VDSGenericException: VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error = Error creating a new volume, code = 205, -- Exception: VDSGenericException: VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error = Error creating a new volume, code = 205 2015-08-28 15:36:11,349 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-80) Task with DB Task ID 639c5e5b-2713-4d9f-b95c-6dd7ed2fc370 and VDSM Task ID c502e058-4b72-4d7f-9c97-a264866289e2 is in state Polling. End action for command 44173a42-970f-42b3-8d09-ca113c58b5df will proceed when all the entitys tasks are completed. 2015-08-28 15:36:11,352 WARN [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-80) SPMAsyncTask::PollTask: Polling task c502e058-4b72-4d7f-9c97-a264866289e2 (Parent Command CreateAllSnapshotsFromVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status aborting. 2015-08-28 15:36:11,355 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-80) Finished polling Tasks, will poll again in 10 seconds. 2015-08-28 15:36:21,367 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSCommand] (DefaultQuartzScheduler_Worker-4) Failed in HSMGetAllTasksStatusesVDS method 2015-08-28 15:36:21,369 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSCommand] (DefaultQuartzScheduler_Worker-4) Failed in HSMGetAllTasksStatusesVDS method 2015-08-28 15:36:21,402 ERROR [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-4) BaseAsyncTask::logEndTaskFailure: Task 0221e559-0eec-468b-bc4f-a7aaa487661a (Parent Command CreateAllSnapshotsFromVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended with failure: -- Result: cleanFailure -- Message: VDSGenericException: VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error = Error creating a new volume, code = 205, -- Exception: VDSGenericException: VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error = Error creating a new volume, code = 205 2015-08-28 15:36:21,409 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-4) Task with DB Task ID 639c5e5b-2713-4d9f-b95c-6dd7ed2fc370 and VDSM Task ID c502e058-4b72-4d7f-9c97-a264866289e2 is in state Polling. End action for command 44173a42-970f-42b3-8d09-ca113c58b5df will proceed when all the entitys tasks are completed. 2015-08-28 15:36:21,412 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-4) SPMAsyncTask::PollTask: Polling task c502e058-4b72-4d7f-9c97-a264866289e2 (Parent Command CreateAllSnapshotsFromVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'cleanFailure'. 2015-08-28 15:36:21,427 ERROR [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-4) BaseAsyncTask::logEndTaskFailure: Task c502e058-4b72-4d7f-9c97-a264866289e2 (Parent Command CreateAllSnapshotsFromVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended with failure: -- Result: cleanFailure -- Message: VDSGenericException: VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error = Error creating a new volume, code = 205, -- Exception: VDSGenericException: VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error = Error creating a new volume, code = 205 2015-08-28 15:36:21,433 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-4) CommandAsyncTask::endActionIfNecessary: All tasks of command 44173a42-970f-42b3-8d09-ca113c58b5df has ended -> executing endAction 2015-08-28 15:36:21,436 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-4) CommandAsyncTask::endAction: Ending action for 2 tasks (command ID: 44173a42-970f-42b3-8d09-ca113c58b5df): calling endAction . 2015-08-28 15:36:21,438 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-21) CommandAsyncTask::endCommandAction [within thread] context: Attempting to endAction CreateAllSnapshotsFromVm, executionIndex: 0 2015-08-28 15:36:21,510 ERROR [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (org.ovirt.thread.pool-8-thread-21) Ending command with failure: org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand 2015-08-28 15:36:21,527 ERROR [org.ovirt.engine.core.bll.CreateSnapshotCommand] (org.ovirt.thread.pool-8-thread-21) [53768935] Ending command with failure: org.ovirt.engine.core.bll.CreateSnapshotCommand 2015-08-28 15:36:21,660 ERROR [org.ovirt.engine.core.bll.CreateSnapshotCommand] (org.ovirt.thread.pool-8-thread-21) [86e8aad] Ending command with failure: org.ovirt.engine.core.bll.CreateSnapshotCommand 2015-08-28 15:36:21,703 INFO [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (org.ovirt.thread.pool-8-thread-21) Lock freed to object EngineLock [exclusiveLocks= key: ee2ea036-2af3-4a18-9329-08a7b0e7ce7c value: VM , sharedLocks= ] 2015-08-28 15:36:21,719 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-21) Correlation ID: 439efb74, Job ID: 760f7564-fb7f-4bec-8ef7-5a1d3b7651fc, Call Stack: null, Custom Event ID: -1, Message: Failed to complete snapshot 'before upg' creation for VM 'Katello_2.2'. 2015-08-28 15:36:21,723 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-21) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type CreateAllSnapshotsFromVm completed, handling the result. 2015-08-28 15:36:21,725 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-21) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type CreateAllSnapshotsFromVm hasn't succeeded, clearing tasks. 2015-08-28 15:36:21,735 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-21) SPMAsyncTask::ClearAsyncTask: Attempting to clear task c502e058-4b72-4d7f-9c97-a264866289e2 2015-08-28 15:36:21,738 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-21) START, SPMClearTaskVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit = false, taskId = c502e058-4b72-4d7f-9c97-a264866289e2), log id: 60494436 2015-08-28 15:36:21,768 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-21) START, HSMClearTaskVDSCommand(HostName = itsatltovirtaio.domain.local, HostId = b783a2ee-4a63-46ca-9afc-b3b74f0e10ce, taskId=c502e058-4b72-4d7f-9c97-a264866289e2), log id: 6a1d669c 2015-08-28 15:36:21,788 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-21) FINISH, HSMClearTaskVDSCommand, log id: 6a1d669c 2015-08-28 15:36:21,790 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-21) FINISH, SPMClearTaskVDSCommand, log id: 60494436 2015-08-28 15:36:21,802 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-21) BaseAsyncTask::removeTaskFromDB: Removed task c502e058-4b72-4d7f-9c97-a264866289e2 from DataBase 2015-08-28 15:36:21,804 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-21) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 0221e559-0eec-468b-bc4f-a7aaa487661a 2015-08-28 15:36:21,806 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-21) START, SPMClearTaskVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit = false, taskId = 0221e559-0eec-468b-bc4f-a7aaa487661a), log id: 76547de3 2015-08-28 15:36:21,836 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-21) START, HSMClearTaskVDSCommand(HostName = itsatltovirtaio.domain.local, HostId = b783a2ee-4a63-46ca-9afc-b3b74f0e10ce, taskId=0221e559-0eec-468b-bc4f-a7aaa487661a), log id: 2514fec6 2015-08-28 15:36:21,856 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-21) FINISH, HSMClearTaskVDSCommand, log id: 2514fec6 2015-08-28 15:36:21,858 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-21) FINISH, SPMClearTaskVDSCommand, log id: 76547de3 2015-08-28 15:36:21,869 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-21) BaseAsyncTask::removeTaskFromDB: Removed task 0221e559-0eec-468b-bc4f-a7aaa487661a from DataBase 2015-08-28 15:36:21,871 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-21) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 44173a42-970f-42b3-8d09-ca113c58b5df ------=_NextPart_000_0197_01D0E1A8.8C2F9BB0 Content-Type: text/html; charset="us-ascii" Content-Transfer-Encoding: quoted-printable <html xmlns:v=3D"urn:schemas-microsoft-com:vml" = xmlns:o=3D"urn:schemas-microsoft-com:office:office" = xmlns:w=3D"urn:schemas-microsoft-com:office:word" = xmlns:m=3D"http://schemas.microsoft.com/office/2004/12/omml" = xmlns=3D"http://www.w3.org/TR/REC-html40"><head><meta = http-equiv=3DContent-Type content=3D"text/html; = charset=3Dus-ascii"><meta name=3DGenerator content=3D"Microsoft Word 14 = (filtered medium)"><style></style></head><body lang=3DDE-AT link=3Dblue = vlink=3Dpurple><div class=3DWordSection1>Hi all,<o:p></o:p><o:p> </o:p>I have a = problem to perform a Snapshot on one of my important VMs, can anyone = please be so kind and assist me…<o:p></o:p><o:p> </o:p>#### start = of problematic vm snapshot ####<o:p></o:p>2015-08-28 = 15:36:08,172 INFO  = [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] = (ajp--127.0.0.1-8702-6) [439efb74] Lock Acquired to object EngineLock = [exclusiveLocks=3D key: ee2ea036-2af3-4a18-9329-08a7b0e7ce7c value: = VM<o:p></o:p>, sharedLocks=3D ]<o:p></o:p>2015-08-28 = 15:36:08,224 INFO  = [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] = (org.ovirt.thread.pool-8-thread-45) Command = 44173a42-970f-42b3-8d09-ca113c58b5df persisting async task placeholder = for child command = be3c1922-aca6-4caf-a432-34fe95043446<o:p></o:p>2015-08-28 = 15:36:08,367 INFO  = [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] = (org.ovirt.thread.pool-8-thread-45) Command = 44173a42-970f-42b3-8d09-ca113c58b5df persisting async task placeholder = for child command = 03a43c66-0fed-4a82-9139-7b89328f4ae4<o:p></o:p>2015-08-28 = 15:36:08,517 INFO  = [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] = (org.ovirt.thread.pool-8-thread-45) Running command: = CreateAllSnapshotsFromVmCommand internal: false. Entities affected = :  ID: ee2ea036-2af3-4a18-9329-08a7b0e7ce7c Type: VMAction group = MANIPULATE_VM_SNAPSHOTS with role type USER<o:p></o:p>2015-08-28 = 15:36:08,550 INFO  = [org.ovirt.engine.core.bll.CreateSnapshotCommand] = (org.ovirt.thread.pool-8-thread-45) [86e8aad] Running command: = CreateSnapshotCommand internal: true. Entities affected :  ID: = 00000000-0000-0000-0000-000000000000 Type: = Storage<o:p></o:p>2015-08-28 15:36:08,560 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] = (org.ovirt.thread.pool-8-thread-45) [86e8aad] START, = CreateSnapshotVDSCommand( storagePoolId =3D = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit =3D false, = storageDomainId =3D 937822d9-8a59-490f-95b7-48371ae32253, imageGroupId = =3D e7e99288-ad83-406e-9cb6-7a5aa443de9b, imageSizeInBytes =3D = 21474836480, volumeFormat =3D COW, newImageId =3D = 2013aa82-6316-4b54-851b-88bf7f523b9c, newImageDescription =3D , imageId = =3D c5762dec-d9d1-4842-84d1-05896d4d27fb, sourceImageGroupId =3D = e7e99288-ad83-406e-9cb6-7a5aa443de9b), log id: = d1ffce0<o:p></o:p>2015-08-28 15:36:08,567 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] = (org.ovirt.thread.pool-8-thread-45) [86e8aad] -- = executeIrsBrokerCommand: calling 'createVolume' with two new parameters: = description and UUID<o:p></o:p>2015-08-28 15:36:08,655 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] = (org.ovirt.thread.pool-8-thread-45) [86e8aad] FINISH, = CreateSnapshotVDSCommand, return: 2013aa82-6316-4b54-851b-88bf7f523b9c, = log id: d1ffce0<o:p></o:p>2015-08-28 15:36:08,668 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (org.ovirt.thread.pool-8-thread-45) [86e8aad] CommandAsyncTask::Adding = CommandMultiAsyncTasks object for command = 44173a42-970f-42b3-8d09-ca113c58b5df<o:p></o:p>2015-08-28 = 15:36:08,670 INFO  = [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] = (org.ovirt.thread.pool-8-thread-45) [86e8aad] = CommandMultiAsyncTasks::AttachTask: Attaching task = 0221e559-0eec-468b-bc4f-a7aaa487661a to command = 44173a42-970f-42b3-8d09-ca113c58b5df.<o:p></o:p>2015-08-28 = 15:36:08,734 INFO  = [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] = (org.ovirt.thread.pool-8-thread-45) [86e8aad] Adding task = 0221e559-0eec-468b-bc4f-a7aaa487661a (Parent Command = CreateAllSnapshotsFromVm, Parameters Type = org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling = hasn't started yet..<o:p></o:p>2015-08-28 15:36:08,793 INFO  = [org.ovirt.engine.core.bll.CreateSnapshotCommand] = (org.ovirt.thread.pool-8-thread-45) [53768935] Running command: = CreateSnapshotCommand internal: true. Entities affected :  ID: = 00000000-0000-0000-0000-000000000000 Type: = Storage<o:p></o:p>2015-08-28 15:36:08,797 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] = (org.ovirt.thread.pool-8-thread-45) [53768935] START, = CreateSnapshotVDSCommand( storagePoolId =3D = 00000002-0002-0002-0002-000000000021, ignoreFailoverLimit =3D false, = storageDomainId =3D 937822d9-8a59-490f-95b7-48371ae32253, imageGroupId = =3D 6281b597-020d-4ea7-a954-bb798a0ca4f1, imageSizeInBytes =3D = 161061273600, volumeFormat =3D COW, newImageId =3D = fd9c6e36-90ca-488a-8cbd-534a0caf6886, newImageDescription =3D , imageId = =3D 2a2015a1-f62c-4e32-8b04-77ece2ba4cc1, sourceImageGroupId =3D = 6281b597-020d-4ea7-a954-bb798a0ca4f1), log id: = 4bc33f2e<o:p></o:p>2015-08-28 15:36:08,799 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] = (org.ovirt.thread.pool-8-thread-45) [53768935] -- = executeIrsBrokerCommand: calling 'createVolume' with two new parameters: = description and UUID<o:p></o:p>2015-08-28 15:36:09,000 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] = (org.ovirt.thread.pool-8-thread-45) [53768935] FINISH, = CreateSnapshotVDSCommand, return: fd9c6e36-90ca-488a-8cbd-534a0caf6886, = log id: 4bc33f2e<o:p></o:p>2015-08-28 15:36:09,011 INFO  = [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] = (org.ovirt.thread.pool-8-thread-45) [53768935] = CommandMultiAsyncTasks::AttachTask: Attaching task = c502e058-4b72-4d7f-9c97-a264866289e2 to command = 44173a42-970f-42b3-8d09-ca113c58b5df.<o:p></o:p>2015-08-28 = 15:36:09,076 INFO  = [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] = (org.ovirt.thread.pool-8-thread-45) [53768935] Adding task = c502e058-4b72-4d7f-9c97-a264866289e2 (Parent Command = CreateAllSnapshotsFromVm, Parameters Type = org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling = hasn't started yet..<o:p></o:p>2015-08-28 15:36:09,235 INFO  = [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] = (org.ovirt.thread.pool-8-thread-45) Correlation ID: 439efb74, Job ID: = 760f7564-fb7f-4bec-8ef7-5a1d3b7651fc, Call Stack: null, Custom Event ID: = -1, Message: Snapshot 'before upg' creation for VM 'Katello_2.2' was = initiated by admin@internal.<o:p></o:p>2015-08-28 = 15:36:09,237 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (org.ovirt.thread.pool-8-thread-45) BaseAsyncTask::startPollingTask: = Starting to poll task = 0221e559-0eec-468b-bc4f-a7aaa487661a.<o:p></o:p>2015-08-28 = 15:36:09,237 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (org.ovirt.thread.pool-8-thread-45) BaseAsyncTask::startPollingTask: = Starting to poll task = c502e058-4b72-4d7f-9c97-a264866289e2.<o:p></o:p>2015-08-28 = 15:36:11,279 INFO  = [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] = (DefaultQuartzScheduler_Worker-80) Polling and updating Async Tasks: 2 = tasks, 2 tasks to poll now<o:p></o:p>2015-08-28 = 15:36:11,309 ERROR = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSComma= nd] (DefaultQuartzScheduler_Worker-80) Failed in = HSMGetAllTasksStatusesVDS method<o:p></o:p>2015-08-28 = 15:36:11,311 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (DefaultQuartzScheduler_Worker-80) SPMAsyncTask::PollTask: Polling task = 0221e559-0eec-468b-bc4f-a7aaa487661a (Parent Command = CreateAllSnapshotsFromVm, Parameters Type = org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned = status finished, result 'cleanFailure'.<o:p></o:p>2015-08-28 = 15:36:11,343 ERROR [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (DefaultQuartzScheduler_Worker-80) BaseAsyncTask::logEndTaskFailure: = Task 0221e559-0eec-468b-bc4f-a7aaa487661a (Parent Command = CreateAllSnapshotsFromVm, Parameters Type = org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended with = failure:<o:p></o:p>-- Result: cleanFailure<o:p></o:p>-- Message: = VDSGenericException: VDSErrorException: Failed to = HSMGetAllTasksStatusesVDS, error =3D Error creating a new volume, code = =3D 205,<o:p></o:p>-- Exception: VDSGenericException: = VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error =3D Error = creating a new volume, code =3D 205<o:p></o:p>2015-08-28 = 15:36:11,349 INFO  = [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] = (DefaultQuartzScheduler_Worker-80) Task with DB Task ID = 639c5e5b-2713-4d9f-b95c-6dd7ed2fc370 and VDSM Task ID = c502e058-4b72-4d7f-9c97-a264866289e2 is in state Polling. End action for = command 44173a42-970f-42b3-8d09-ca113c58b5df will proceed when all the = entitys tasks are completed.<o:p></o:p>2015-08-28 = 15:36:11,352 WARN  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (DefaultQuartzScheduler_Worker-80) SPMAsyncTask::PollTask: Polling task = c502e058-4b72-4d7f-9c97-a264866289e2 (Parent Command = CreateAllSnapshotsFromVm, Parameters Type = org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned = status aborting.<o:p></o:p>2015-08-28 15:36:11,355 INFO  = [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] = (DefaultQuartzScheduler_Worker-80) Finished polling Tasks, will poll = again in 10 seconds.<o:p></o:p>2015-08-28 15:36:21,367 ERROR = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSComma= nd] (DefaultQuartzScheduler_Worker-4) Failed in = HSMGetAllTasksStatusesVDS method<o:p></o:p>2015-08-28 = 15:36:21,369 ERROR = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSComma= nd] (DefaultQuartzScheduler_Worker-4) Failed in = HSMGetAllTasksStatusesVDS method<o:p></o:p>2015-08-28 = 15:36:21,402 ERROR [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (DefaultQuartzScheduler_Worker-4) BaseAsyncTask::logEndTaskFailure: Task = 0221e559-0eec-468b-bc4f-a7aaa487661a (Parent Command = CreateAllSnapshotsFromVm, Parameters Type = org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended with = failure:<o:p></o:p>-- Result: cleanFailure<o:p></o:p>-- Message: = VDSGenericException: VDSErrorException: Failed to = HSMGetAllTasksStatusesVDS, error =3D Error creating a new volume, code = =3D 205,<o:p></o:p>-- Exception: VDSGenericException: = VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error =3D Error = creating a new volume, code =3D 205<o:p></o:p>2015-08-28 = 15:36:21,409 INFO  = [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] = (DefaultQuartzScheduler_Worker-4) Task with DB Task ID = 639c5e5b-2713-4d9f-b95c-6dd7ed2fc370 and VDSM Task ID = c502e058-4b72-4d7f-9c97-a264866289e2 is in state Polling. End action for = command 44173a42-970f-42b3-8d09-ca113c58b5df will proceed when all the = entitys tasks are completed.<o:p></o:p>2015-08-28 = 15:36:21,412 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (DefaultQuartzScheduler_Worker-4) SPMAsyncTask::PollTask: Polling task = c502e058-4b72-4d7f-9c97-a264866289e2 (Parent Command = CreateAllSnapshotsFromVm, Parameters Type = org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned = status finished, result 'cleanFailure'.<o:p></o:p>2015-08-28 = 15:36:21,427 ERROR [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (DefaultQuartzScheduler_Worker-4) BaseAsyncTask::logEndTaskFailure: Task = c502e058-4b72-4d7f-9c97-a264866289e2 (Parent Command = CreateAllSnapshotsFromVm, Parameters Type = org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended with = failure:<o:p></o:p>-- Result: cleanFailure<o:p></o:p>-- Message: = VDSGenericException: VDSErrorException: Failed to = HSMGetAllTasksStatusesVDS, error =3D Error creating a new volume, code = =3D 205,<o:p></o:p>-- Exception: VDSGenericException: = VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error =3D Error = creating a new volume, code =3D 205<o:p></o:p>2015-08-28 = 15:36:21,433 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (DefaultQuartzScheduler_Worker-4) = CommandAsyncTask::endActionIfNecessary: All tasks of command = 44173a42-970f-42b3-8d09-ca113c58b5df has ended -> executing = endAction<o:p></o:p>2015-08-28 15:36:21,436 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (DefaultQuartzScheduler_Worker-4) CommandAsyncTask::endAction: Ending = action for 2 tasks (command ID: 44173a42-970f-42b3-8d09-ca113c58b5df): = calling endAction .<o:p></o:p>2015-08-28 15:36:21,438 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (org.ovirt.thread.pool-8-thread-21) CommandAsyncTask::endCommandAction = [within thread] context: Attempting to endAction = CreateAllSnapshotsFromVm, executionIndex: 0<o:p></o:p>2015-08-28 = 15:36:21,510 ERROR = [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] = (org.ovirt.thread.pool-8-thread-21) Ending command with failure: = org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand<o:p></o:p></spa= n>2015-08-28 15:36:21,527 ERROR = [org.ovirt.engine.core.bll.CreateSnapshotCommand] = (org.ovirt.thread.pool-8-thread-21) [53768935] Ending command with = failure: = org.ovirt.engine.core.bll.CreateSnapshotCommand<o:p></o:p>2015-08-28 = 15:36:21,660 ERROR [org.ovirt.engine.core.bll.CreateSnapshotCommand] = (org.ovirt.thread.pool-8-thread-21) [86e8aad] Ending command with = failure: = org.ovirt.engine.core.bll.CreateSnapshotCommand<o:p></o:p>2015-08-28 = 15:36:21,703 INFO  = [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] = (org.ovirt.thread.pool-8-thread-21) Lock freed to object EngineLock = [exclusiveLocks=3D key: ee2ea036-2af3-4a18-9329-08a7b0e7ce7c value: = VM<o:p></o:p>, sharedLocks=3D ]<o:p></o:p>2015-08-28 = 15:36:21,719 ERROR = [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] = (org.ovirt.thread.pool-8-thread-21) Correlation ID: 439efb74, Job ID: = 760f7564-fb7f-4bec-8ef7-5a1d3b7651fc, Call Stack: null, Custom Event ID: = -1, Message: Failed to complete snapshot 'before upg' creation for VM = 'Katello_2.2'.<o:p></o:p>2015-08-28 15:36:21,723 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (org.ovirt.thread.pool-8-thread-21) = CommandAsyncTask::HandleEndActionResult [within thread]: endAction for = action type CreateAllSnapshotsFromVm completed, handling the = result.<o:p></o:p>2015-08-28 15:36:21,725 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (org.ovirt.thread.pool-8-thread-21) = CommandAsyncTask::HandleEndActionResult [within thread]: endAction for = action type CreateAllSnapshotsFromVm hasn't succeeded, clearing = tasks.<o:p></o:p>2015-08-28 15:36:21,735 INFO  = [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (org.ovirt.thread.pool-8-thread-21) SPMAsyncTask::ClearAsyncTask: = Attempting to clear task = c502e058-4b72-4d7f-9c97-a264866289e2<o:p></o:p>2015-08-28 = 15:36:21,738 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-21) START, SPMClearTaskVDSCommand( = storagePoolId =3D 00000002-0002-0002-0002-000000000021, = ignoreFailoverLimit =3D false, taskId =3D = c502e058-4b72-4d7f-9c97-a264866289e2), log id: = 60494436<o:p></o:p>2015-08-28 15:36:21,768 INFO  = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-21) START, = HSMClearTaskVDSCommand(HostName =3D itsatltovirtaio.domain.local, HostId = =3D b783a2ee-4a63-46ca-9afc-b3b74f0e10ce, = taskId=3Dc502e058-4b72-4d7f-9c97-a264866289e2), log id: = 6a1d669c<o:p></o:p>2015-08-28 15:36:21,788 INFO  = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-21) FINISH, HSMClearTaskVDSCommand, log = id: 6a1d669c<o:p></o:p>2015-08-28 15:36:21,790 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-21) FINISH, SPMClearTaskVDSCommand, log = id: 60494436<o:p></o:p>2015-08-28 15:36:21,802 INFO  = [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (org.ovirt.thread.pool-8-thread-21) BaseAsyncTask::removeTaskFromDB: = Removed task c502e058-4b72-4d7f-9c97-a264866289e2 from = DataBase<o:p></o:p>2015-08-28 15:36:21,804 INFO  = [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (org.ovirt.thread.pool-8-thread-21) SPMAsyncTask::ClearAsyncTask: = Attempting to clear task = 0221e559-0eec-468b-bc4f-a7aaa487661a<o:p></o:p>2015-08-28 = 15:36:21,806 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-21) START, SPMClearTaskVDSCommand( = storagePoolId =3D 00000002-0002-0002-0002-000000000021, = ignoreFailoverLimit =3D false, taskId =3D = 0221e559-0eec-468b-bc4f-a7aaa487661a), log id: = 76547de3<o:p></o:p>2015-08-28 15:36:21,836 INFO  = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-21) START, = HSMClearTaskVDSCommand(HostName =3D itsatltovirtaio.domain.local, HostId = =3D b783a2ee-4a63-46ca-9afc-b3b74f0e10ce, = taskId=3D0221e559-0eec-468b-bc4f-a7aaa487661a), log id: = 2514fec6<o:p></o:p>2015-08-28 15:36:21,856 INFO  = [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-21) FINISH, HSMClearTaskVDSCommand, log = id: 2514fec6<o:p></o:p>2015-08-28 15:36:21,858 INFO  = [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] = (org.ovirt.thread.pool-8-thread-21) FINISH, SPMClearTaskVDSCommand, log = id: 76547de3<o:p></o:p>2015-08-28 15:36:21,869 INFO  = [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] = (org.ovirt.thread.pool-8-thread-21) BaseAsyncTask::removeTaskFromDB: = Removed task 0221e559-0eec-468b-bc4f-a7aaa487661a from = DataBase<o:p></o:p>2015-08-28 15:36:21,871 INFO  = [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] = (org.ovirt.thread.pool-8-thread-21) = CommandAsyncTask::HandleEndActionResult [within thread]: Removing = CommandMultiAsyncTasks object for entity = 44173a42-970f-42b3-8d09-ca113c58b5df<o:p></o:p><o:p> </o:p><o:p> </o:p><o:p> </o:p><o:p> </o:p><o:p> </o:p></div></body></html> ------=_NextPart_000_0197_01D0E1A8.8C2F9BB0--

1 0