Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 918355 Details for
Bug 1120087
Disk format doesn't change back to the original after being imported with collapse snapshot
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
Engine log disk id is "710a1b29-5037-435e-8f38-44edc78dc175"
engine-import_vm_prealloc.log (text/x-log), 140.28 KB, created by
Carlos Mestre González
on 2014-07-16 09:03:07 UTC
(
hide
)
Description:
Engine log disk id is "710a1b29-5037-435e-8f38-44edc78dc175"
Filename:
MIME Type:
Creator:
Carlos Mestre González
Created:
2014-07-16 09:03:07 UTC
Size:
140.28 KB
patch
obsolete
> >2014-07-15 20:52:56,547 INFO [org.ovirt.engine.core.bll.network.vm.AddVmInterfaceCommand] (ajp-/127.0.0.1:8702-1) [a7b690e8-bdc8-404f_addNic_create] Running command: AddVmInterfaceCommand internal: false. Entities affected : ID: 75366289-1e23-4171-8df7-95945a59ab3c Type: VM, ID: ee81d271-0c1d-4319-9e95-3e36faef0e38 Type: VnicProfile >2014-07-15 20:52:56,612 INFO [org.ovirt.engine.core.bll.network.vm.ActivateDeactivateVmNicCommand] (ajp-/127.0.0.1:8702-1) [200e3109] Running command: ActivateDeactivateVmNicCommand internal: true. Entities affected : ID: 75366289-1e23-4171-8df7-95945a59ab3c Type: VM >2014-07-15 20:52:56,667 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-1) [200e3109] Correlation ID: 200e3109, Call Stack: null, Custom Event ID: -1, Message: Network Interface eth0 (VirtIO) was plugged to VM vm_prealloc_8090. (User: admin) >2014-07-15 20:52:56,691 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-1) [200e3109] Correlation ID: a7b690e8-bdc8-404f_addNic_create, Call Stack: null, Custom Event ID: -1, Message: Interface eth0 (VirtIO) was added to VM vm_prealloc_8090. (User: admin) >2014-07-15 20:52:56,719 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Running command: LoginUserCommand internal: false. >2014-07-15 20:52:56,732 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-2) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin logged in. >2014-07-15 20:52:57,044 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Running command: LoginUserCommand internal: false. >2014-07-15 20:52:57,129 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Running command: LoginUserCommand internal: false. >2014-07-15 20:52:57,157 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (ajp-/127.0.0.1:8702-7) [f1a02a5a-a418-4361_addDisk_create] Lock Acquired to object EngineLock [exclusiveLocks= key: ea94cd0b-a6bf-43d2-9293-01e471867eee value: VM_DISK_BOOT >, sharedLocks= key: ea94cd0b-a6bf-43d2-9293-01e471867eee value: VM >] >2014-07-15 20:52:57,220 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (ajp-/127.0.0.1:8702-7) [f1a02a5a-a418-4361_addDisk_create] Running command: AddDiskCommand internal: false. Entities affected : ID: ea94cd0b-a6bf-43d2-9293-01e471867eee Type: VM, ID: 8e09f329-8246-41b0-afd5-d214f2f7e86d Type: Storage >2014-07-15 20:52:57,221 INFO [org.ovirt.engine.core.bll.ImagesHandler] (ajp-/127.0.0.1:8702-7) [f1a02a5a-a418-4361_addDisk_create] Disk alias retrieved from the client is null or empty, the suggested default disk alias to be used is vm_thin_8090_Disk1 >2014-07-15 20:52:57,339 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (ajp-/127.0.0.1:8702-7) [6b589fa6] Running command: AddImageFromScratchCommand internal: true. Entities affected : ID: 8e09f329-8246-41b0-afd5-d214f2f7e86d Type: Storage >2014-07-15 20:52:57,439 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (ajp-/127.0.0.1:8702-7) [6b589fa6] Lock freed to object EngineLock [exclusiveLocks= key: ea94cd0b-a6bf-43d2-9293-01e471867eee value: VM_DISK_BOOT >, sharedLocks= key: ea94cd0b-a6bf-43d2-9293-01e471867eee value: VM >] >2014-07-15 20:52:57,439 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp-/127.0.0.1:8702-7) [6b589fa6] START, CreateImageVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 31201085-616a-4d7f-8334-109d0491eae6, imageSizeInBytes = 1073741824, volumeFormat = COW, newImageId = 18ed4347-ddb8-463e-a86f-9cb5859ad0ad, newImageDescription = ), log id: 3267483d >2014-07-15 20:52:57,445 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp-/127.0.0.1:8702-7) [6b589fa6] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID >2014-07-15 20:52:57,581 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp-/127.0.0.1:8702-7) [6b589fa6] FINISH, CreateImageVDSCommand, return: 18ed4347-ddb8-463e-a86f-9cb5859ad0ad, log id: 3267483d >2014-07-15 20:52:57,601 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp-/127.0.0.1:8702-7) [6b589fa6] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 7654ba17-ecb1-4bea-b684-942d058a1a3f >2014-07-15 20:52:57,601 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp-/127.0.0.1:8702-7) [6b589fa6] CommandMultiAsyncTasks::AttachTask: Attaching task f9bb4398-bd63-4b08-ada1-f5a976061e53 to command 7654ba17-ecb1-4bea-b684-942d058a1a3f. >2014-07-15 20:52:57,619 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-7) [6b589fa6] Adding task f9bb4398-bd63-4b08-ada1-f5a976061e53 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-15 20:52:57,652 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-7) [6b589fa6] Correlation ID: f1a02a5a-a418-4361_addDisk_create, Job ID: 060d1d75-ba47-4f57-9197-617d8aa651d6, Call Stack: null, Custom Event ID: -1, Message: Add-Disk operation of vm_thin_8090_Disk1 was initiated on VM vm_thin_8090 by admin. >2014-07-15 20:52:57,652 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-7) [6b589fa6] BaseAsyncTask::startPollingTask: Starting to poll task f9bb4398-bd63-4b08-ada1-f5a976061e53. >2014-07-15 20:52:57,901 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (ajp-/127.0.0.1:8702-2) [d9cc76a4-451a-49d1_addDisk_create] Lock Acquired to object EngineLock [exclusiveLocks= key: 75366289-1e23-4171-8df7-95945a59ab3c value: VM_DISK_BOOT >, sharedLocks= key: 75366289-1e23-4171-8df7-95945a59ab3c value: VM >] >2014-07-15 20:52:57,957 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (ajp-/127.0.0.1:8702-2) [d9cc76a4-451a-49d1_addDisk_create] Running command: AddDiskCommand internal: false. Entities affected : ID: 75366289-1e23-4171-8df7-95945a59ab3c Type: VM, ID: 8e09f329-8246-41b0-afd5-d214f2f7e86d Type: Storage >2014-07-15 20:52:57,958 INFO [org.ovirt.engine.core.bll.ImagesHandler] (ajp-/127.0.0.1:8702-2) [d9cc76a4-451a-49d1_addDisk_create] Disk alias retrieved from the client is null or empty, the suggested default disk alias to be used is vm_prealloc_8090_Disk1 >2014-07-15 20:52:58,051 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (ajp-/127.0.0.1:8702-2) [3ef928c7] Running command: AddImageFromScratchCommand internal: true. Entities affected : ID: 8e09f329-8246-41b0-afd5-d214f2f7e86d Type: Storage >2014-07-15 20:52:58,114 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (ajp-/127.0.0.1:8702-2) [3ef928c7] Lock freed to object EngineLock [exclusiveLocks= key: 75366289-1e23-4171-8df7-95945a59ab3c value: VM_DISK_BOOT >, sharedLocks= key: 75366289-1e23-4171-8df7-95945a59ab3c value: VM >] >2014-07-15 20:52:58,115 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp-/127.0.0.1:8702-2) [3ef928c7] START, CreateImageVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 710a1b29-5037-435e-8f38-44edc78dc175, imageSizeInBytes = 1073741824, volumeFormat = RAW, newImageId = 15928c43-6024-4b76-aca3-18dd7c64bce1, newImageDescription = ), log id: 78edb196 >2014-07-15 20:52:58,115 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp-/127.0.0.1:8702-2) [3ef928c7] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID >2014-07-15 20:52:58,140 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-4) Running command: LoginUserCommand internal: false. >2014-07-15 20:52:58,280 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp-/127.0.0.1:8702-2) [3ef928c7] FINISH, CreateImageVDSCommand, return: 15928c43-6024-4b76-aca3-18dd7c64bce1, log id: 78edb196 >2014-07-15 20:52:58,305 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp-/127.0.0.1:8702-2) [3ef928c7] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 59ffbaf6-9eea-4396-a0fb-de05155a0f6a >2014-07-15 20:52:58,310 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp-/127.0.0.1:8702-2) [3ef928c7] CommandMultiAsyncTasks::AttachTask: Attaching task 843c515f-83ba-4f8d-a59c-8397eae52ef5 to command 59ffbaf6-9eea-4396-a0fb-de05155a0f6a. >2014-07-15 20:52:58,339 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-2) [3ef928c7] Adding task 843c515f-83ba-4f8d-a59c-8397eae52ef5 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-15 20:52:58,406 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-2) [3ef928c7] Correlation ID: d9cc76a4-451a-49d1_addDisk_create, Job ID: 42e95e1a-fea8-4306-afee-67ab5b3f32e8, Call Stack: null, Custom Event ID: -1, Message: Add-Disk operation of vm_prealloc_8090_Disk1 was initiated on VM vm_prealloc_8090 by admin. >2014-07-15 20:52:58,406 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-2) [3ef928c7] BaseAsyncTask::startPollingTask: Starting to poll task 843c515f-83ba-4f8d-a59c-8397eae52ef5. >2014-07-15 20:52:58,463 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Running command: LoginUserCommand internal: false. >2014-07-15 20:53:00,205 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-40) Polling and updating Async Tasks: 6 tasks, 2 tasks to poll now >2014-07-15 20:53:00,237 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-40) SPMAsyncTask::PollTask: Polling task f9bb4398-bd63-4b08-ada1-f5a976061e53 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-15 20:53:00,246 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-40) BaseAsyncTask::OnTaskEndSuccess: Task f9bb4398-bd63-4b08-ada1-f5a976061e53 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-15 20:53:00,247 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-40) CommandAsyncTask::EndActionIfNecessary: All tasks of command 7654ba17-ecb1-4bea-b684-942d058a1a3f has ended -> executing endAction >2014-07-15 20:53:00,247 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-40) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: 7654ba17-ecb1-4bea-b684-942d058a1a3f): calling endAction . >2014-07-15 20:53:00,247 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-40) SPMAsyncTask::PollTask: Polling task 843c515f-83ba-4f8d-a59c-8397eae52ef5 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status running. >2014-07-15 20:53:00,247 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-40) Finished polling Tasks, will poll again in 10 seconds. >2014-07-15 20:53:00,247 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-7) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction AddDisk, executionIndex: 0 >2014-07-15 20:53:00,272 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (org.ovirt.thread.pool-4-thread-7) [f1a02a5a-a418-4361_addDisk_create] Ending command successfully: org.ovirt.engine.core.bll.AddDiskCommand >2014-07-15 20:53:00,279 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (org.ovirt.thread.pool-4-thread-7) [6b589fa6] Ending command successfully: org.ovirt.engine.core.bll.AddImageFromScratchCommand >2014-07-15 20:53:00,288 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-4-thread-7) [6b589fa6] START, GetImageInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 31201085-616a-4d7f-8334-109d0491eae6, imageId = 18ed4347-ddb8-463e-a86f-9cb5859ad0ad), log id: b919f96 >2014-07-15 20:53:00,373 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-4-thread-7) [6b589fa6] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@80dc3ea7, log id: b919f96 >2014-07-15 20:53:00,401 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-4-thread-7) Correlation ID: f1a02a5a-a418-4361_addDisk_create, Job ID: 060d1d75-ba47-4f57-9197-617d8aa651d6, Call Stack: null, Custom Event ID: -1, Message: The disk vm_thin_8090_Disk1 was successfully added to VM vm_thin_8090. >2014-07-15 20:53:00,402 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-7) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk completed, handling the result. >2014-07-15 20:53:00,402 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-7) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk succeeded, clearing tasks. >2014-07-15 20:53:00,409 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-7) SPMAsyncTask::ClearAsyncTask: Attempting to clear task f9bb4398-bd63-4b08-ada1-f5a976061e53 >2014-07-15 20:53:00,410 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-7) START, SPMClearTaskVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, taskId = f9bb4398-bd63-4b08-ada1-f5a976061e53), log id: 7e93c2bb >2014-07-15 20:53:00,412 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-7) START, HSMClearTaskVDSCommand(HostName = 10.35.160.41, HostId = 64f3caf5-d883-4e40-b0e5-e81e2bf7f781, taskId=f9bb4398-bd63-4b08-ada1-f5a976061e53), log id: 1af78fdf >2014-07-15 20:53:00,464 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-7) FINISH, HSMClearTaskVDSCommand, log id: 1af78fdf >2014-07-15 20:53:00,464 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-7) FINISH, SPMClearTaskVDSCommand, log id: 7e93c2bb >2014-07-15 20:53:00,467 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-7) BaseAsyncTask::RemoveTaskFromDB: Removed task f9bb4398-bd63-4b08-ada1-f5a976061e53 from DataBase >2014-07-15 20:53:00,467 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-7) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 7654ba17-ecb1-4bea-b684-942d058a1a3f >2014-07-15 20:53:00,672 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-36) Setting new tasks map. The map contains now 3 tasks >2014-07-15 20:53:10,248 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-60) Polling and updating Async Tasks: 3 tasks, 1 tasks to poll now >2014-07-15 20:53:10,277 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-60) Finished polling Tasks, will poll again in 10 seconds. >2014-07-15 20:53:20,307 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-80) SPMAsyncTask::PollTask: Polling task 843c515f-83ba-4f8d-a59c-8397eae52ef5 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-15 20:53:20,315 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-80) BaseAsyncTask::OnTaskEndSuccess: Task 843c515f-83ba-4f8d-a59c-8397eae52ef5 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-15 20:53:20,316 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-80) CommandAsyncTask::EndActionIfNecessary: All tasks of command 59ffbaf6-9eea-4396-a0fb-de05155a0f6a has ended -> executing endAction >2014-07-15 20:53:20,316 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-80) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: 59ffbaf6-9eea-4396-a0fb-de05155a0f6a): calling endAction . >2014-07-15 20:53:20,316 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-31) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction AddDisk, executionIndex: 0 >2014-07-15 20:53:20,332 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (org.ovirt.thread.pool-4-thread-31) [d9cc76a4-451a-49d1_addDisk_create] Ending command successfully: org.ovirt.engine.core.bll.AddDiskCommand >2014-07-15 20:53:20,339 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (org.ovirt.thread.pool-4-thread-31) [3ef928c7] Ending command successfully: org.ovirt.engine.core.bll.AddImageFromScratchCommand >2014-07-15 20:53:20,347 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-4-thread-31) [3ef928c7] START, GetImageInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 710a1b29-5037-435e-8f38-44edc78dc175, imageId = 15928c43-6024-4b76-aca3-18dd7c64bce1), log id: 74926b12 >2014-07-15 20:53:20,419 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-4-thread-31) [3ef928c7] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@5665c651, log id: 74926b12 >2014-07-15 20:53:20,448 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-4-thread-31) Correlation ID: d9cc76a4-451a-49d1_addDisk_create, Job ID: 42e95e1a-fea8-4306-afee-67ab5b3f32e8, Call Stack: null, Custom Event ID: -1, Message: The disk vm_prealloc_8090_Disk1 was successfully added to VM vm_prealloc_8090. >2014-07-15 20:53:20,448 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-31) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk completed, handling the result. >2014-07-15 20:53:20,449 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-31) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk succeeded, clearing tasks. >2014-07-15 20:53:20,454 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-31) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 843c515f-83ba-4f8d-a59c-8397eae52ef5 >2014-07-15 20:53:20,454 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-31) START, SPMClearTaskVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, taskId = 843c515f-83ba-4f8d-a59c-8397eae52ef5), log id: 57d51723 >2014-07-15 20:53:20,456 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-31) START, HSMClearTaskVDSCommand(HostName = 10.35.160.41, HostId = 64f3caf5-d883-4e40-b0e5-e81e2bf7f781, taskId=843c515f-83ba-4f8d-a59c-8397eae52ef5), log id: 66e227e1 >2014-07-15 20:53:20,494 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-31) FINISH, HSMClearTaskVDSCommand, log id: 66e227e1 >2014-07-15 20:53:20,494 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-31) FINISH, SPMClearTaskVDSCommand, log id: 57d51723 >2014-07-15 20:53:20,497 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-31) BaseAsyncTask::RemoveTaskFromDB: Removed task 843c515f-83ba-4f8d-a59c-8397eae52ef5 from DataBase >2014-07-15 20:53:20,497 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-31) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 59ffbaf6-9eea-4396-a0fb-de05155a0f6a >2014-07-15 20:53:30,621 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-3) Running command: LoginUserCommand internal: false. >2014-07-15 20:53:30,629 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-3) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin logged in. >2014-07-15 20:53:30,673 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-93) Setting new tasks map. The map contains now 2 tasks >2014-07-15 20:53:30,693 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Running command: LoginUserCommand internal: false. >2014-07-15 20:53:30,776 INFO [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (ajp-/127.0.0.1:8702-2) Lock Acquired to object EngineLock [exclusiveLocks= key: ea94cd0b-a6bf-43d2-9293-01e471867eee value: VM >, sharedLocks= ] >2014-07-15 20:53:30,810 INFO [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (ajp-/127.0.0.1:8702-2) Running command: CreateAllSnapshotsFromVmCommand internal: false. Entities affected : ID: ea94cd0b-a6bf-43d2-9293-01e471867eee Type: VM >2014-07-15 20:53:30,827 INFO [org.ovirt.engine.core.bll.CreateSnapshotCommand] (ajp-/127.0.0.1:8702-2) [4023eb9] Running command: CreateSnapshotCommand internal: true. Entities affected : ID: 00000000-0000-0000-0000-000000000000 Type: Storage >2014-07-15 20:53:30,838 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (ajp-/127.0.0.1:8702-2) [4023eb9] START, CreateSnapshotVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 31201085-616a-4d7f-8334-109d0491eae6, imageSizeInBytes = 1073741824, volumeFormat = COW, newImageId = a12fab0f-1b8e-4ef9-99f7-ce15fa5e4a37, newImageDescription = , imageId = 18ed4347-ddb8-463e-a86f-9cb5859ad0ad, sourceImageGroupId = 31201085-616a-4d7f-8334-109d0491eae6), log id: 307cd198 >2014-07-15 20:53:30,838 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (ajp-/127.0.0.1:8702-2) [4023eb9] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID >2014-07-15 20:53:30,930 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-5) Running command: LoginUserCommand internal: false. >2014-07-15 20:53:30,985 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (ajp-/127.0.0.1:8702-2) [4023eb9] FINISH, CreateSnapshotVDSCommand, return: a12fab0f-1b8e-4ef9-99f7-ce15fa5e4a37, log id: 307cd198 >2014-07-15 20:53:30,997 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp-/127.0.0.1:8702-2) [4023eb9] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command be74faae-6915-4581-96b7-54af870eb889 >2014-07-15 20:53:30,997 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp-/127.0.0.1:8702-2) [4023eb9] CommandMultiAsyncTasks::AttachTask: Attaching task 441c17bc-f57d-44ba-b7e1-3c9faea5afeb to command be74faae-6915-4581-96b7-54af870eb889. >2014-07-15 20:53:31,007 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-2) [4023eb9] Adding task 441c17bc-f57d-44ba-b7e1-3c9faea5afeb (Parent Command CreateAllSnapshotsFromVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-15 20:53:31,049 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-2) Correlation ID: fe973ca9-6309-495c_addSnapshot_create, Job ID: 537a0b59-3e66-465f-a58d-00bc609a7c72, Call Stack: null, Custom Event ID: -1, Message: Snapshot 'snapshot for vm vm_thin_8090' creation for VM 'vm_thin_8090' was initiated by admin. >2014-07-15 20:53:31,049 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-2) BaseAsyncTask::startPollingTask: Starting to poll task 441c17bc-f57d-44ba-b7e1-3c9faea5afeb. >2014-07-15 20:53:31,306 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Running command: LoginUserCommand internal: false. >2014-07-15 20:53:31,396 INFO [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (ajp-/127.0.0.1:8702-6) Lock Acquired to object EngineLock [exclusiveLocks= key: 75366289-1e23-4171-8df7-95945a59ab3c value: VM >, sharedLocks= ] >2014-07-15 20:53:31,423 INFO [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (ajp-/127.0.0.1:8702-6) Running command: CreateAllSnapshotsFromVmCommand internal: false. Entities affected : ID: 75366289-1e23-4171-8df7-95945a59ab3c Type: VM >2014-07-15 20:53:31,440 INFO [org.ovirt.engine.core.bll.CreateSnapshotCommand] (ajp-/127.0.0.1:8702-6) [15e47927] Running command: CreateSnapshotCommand internal: true. Entities affected : ID: 00000000-0000-0000-0000-000000000000 Type: Storage >2014-07-15 20:53:31,450 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (ajp-/127.0.0.1:8702-6) [15e47927] START, CreateSnapshotVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 710a1b29-5037-435e-8f38-44edc78dc175, imageSizeInBytes = 1073741824, volumeFormat = COW, newImageId = bbc130ad-11bb-48df-a4a7-a43be27c1801, newImageDescription = , imageId = 15928c43-6024-4b76-aca3-18dd7c64bce1, sourceImageGroupId = 710a1b29-5037-435e-8f38-44edc78dc175), log id: 7cc483f0 >2014-07-15 20:53:31,450 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (ajp-/127.0.0.1:8702-6) [15e47927] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID >2014-07-15 20:53:31,572 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (ajp-/127.0.0.1:8702-6) [15e47927] FINISH, CreateSnapshotVDSCommand, return: bbc130ad-11bb-48df-a4a7-a43be27c1801, log id: 7cc483f0 >2014-07-15 20:53:31,584 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp-/127.0.0.1:8702-6) [15e47927] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 6f9e933d-8e93-440d-846f-7d2111cc3bf8 >2014-07-15 20:53:31,584 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp-/127.0.0.1:8702-6) [15e47927] CommandMultiAsyncTasks::AttachTask: Attaching task 49c2f9b6-99ce-4d9a-82d3-655f45cd4677 to command 6f9e933d-8e93-440d-846f-7d2111cc3bf8. >2014-07-15 20:53:31,594 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-6) [15e47927] Adding task 49c2f9b6-99ce-4d9a-82d3-655f45cd4677 (Parent Command CreateAllSnapshotsFromVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-15 20:53:31,638 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-6) Correlation ID: 8ce54bc5-c1c4-4ab9_addSnapshot_create, Job ID: 954d23f7-5770-4c0c-a2cd-95c58a14aebb, Call Stack: null, Custom Event ID: -1, Message: Snapshot 'snapshot for vm vm_prealloc_8090' creation for VM 'vm_prealloc_8090' was initiated by admin. >2014-07-15 20:53:31,638 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-6) BaseAsyncTask::startPollingTask: Starting to poll task 49c2f9b6-99ce-4d9a-82d3-655f45cd4677. >2014-07-15 20:53:40,318 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-18) Polling and updating Async Tasks: 4 tasks, 2 tasks to poll now >2014-07-15 20:53:40,349 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-18) SPMAsyncTask::PollTask: Polling task 49c2f9b6-99ce-4d9a-82d3-655f45cd4677 (Parent Command CreateAllSnapshotsFromVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-15 20:53:40,359 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-18) BaseAsyncTask::OnTaskEndSuccess: Task 49c2f9b6-99ce-4d9a-82d3-655f45cd4677 (Parent Command CreateAllSnapshotsFromVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-15 20:53:40,360 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-18) CommandAsyncTask::EndActionIfNecessary: All tasks of command 6f9e933d-8e93-440d-846f-7d2111cc3bf8 has ended -> executing endAction >2014-07-15 20:53:40,360 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-18) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: 6f9e933d-8e93-440d-846f-7d2111cc3bf8): calling endAction . >2014-07-15 20:53:40,361 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-20) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction CreateAllSnapshotsFromVm, executionIndex: 0 >2014-07-15 20:53:40,364 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-18) SPMAsyncTask::PollTask: Polling task 441c17bc-f57d-44ba-b7e1-3c9faea5afeb (Parent Command CreateAllSnapshotsFromVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-15 20:53:40,373 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-18) BaseAsyncTask::OnTaskEndSuccess: Task 441c17bc-f57d-44ba-b7e1-3c9faea5afeb (Parent Command CreateAllSnapshotsFromVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-15 20:53:40,374 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-18) CommandAsyncTask::EndActionIfNecessary: All tasks of command be74faae-6915-4581-96b7-54af870eb889 has ended -> executing endAction >2014-07-15 20:53:40,374 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-18) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: be74faae-6915-4581-96b7-54af870eb889): calling endAction . >2014-07-15 20:53:40,374 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-49) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction CreateAllSnapshotsFromVm, executionIndex: 0 >2014-07-15 20:53:40,420 INFO [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (org.ovirt.thread.pool-4-thread-49) [fe973ca9-6309-495c_addSnapshot_create] Ending command successfully: org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand >2014-07-15 20:53:40,421 INFO [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (org.ovirt.thread.pool-4-thread-20) [8ce54bc5-c1c4-4ab9_addSnapshot_create] Ending command successfully: org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand >2014-07-15 20:53:40,427 INFO [org.ovirt.engine.core.bll.CreateSnapshotCommand] (org.ovirt.thread.pool-4-thread-49) [4023eb9] Ending command successfully: org.ovirt.engine.core.bll.CreateSnapshotCommand >2014-07-15 20:53:40,429 INFO [org.ovirt.engine.core.bll.CreateSnapshotCommand] (org.ovirt.thread.pool-4-thread-20) [15e47927] Ending command successfully: org.ovirt.engine.core.bll.CreateSnapshotCommand >2014-07-15 20:53:40,436 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-4-thread-49) [4023eb9] START, GetImageInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 31201085-616a-4d7f-8334-109d0491eae6, imageId = a12fab0f-1b8e-4ef9-99f7-ce15fa5e4a37), log id: 2523e49 >2014-07-15 20:53:40,438 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-4-thread-20) [15e47927] START, GetImageInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 710a1b29-5037-435e-8f38-44edc78dc175, imageId = bbc130ad-11bb-48df-a4a7-a43be27c1801), log id: 72dd5713 >2014-07-15 20:53:40,512 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-4-thread-49) [4023eb9] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@ed802f35, log id: 2523e49 >2014-07-15 20:53:40,580 INFO [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (org.ovirt.thread.pool-4-thread-49) Lock freed to object EngineLock [exclusiveLocks= key: ea94cd0b-a6bf-43d2-9293-01e471867eee value: VM >, sharedLocks= ] >2014-07-15 20:53:40,602 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-4-thread-20) [15e47927] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@e6e9e53b, log id: 72dd5713 >2014-07-15 20:53:40,611 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-4-thread-49) Correlation ID: fe973ca9-6309-495c_addSnapshot_create, Job ID: 537a0b59-3e66-465f-a58d-00bc609a7c72, Call Stack: null, Custom Event ID: -1, Message: Snapshot 'snapshot for vm vm_thin_8090' creation for VM 'vm_thin_8090' has been completed. >2014-07-15 20:53:40,611 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-49) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type CreateAllSnapshotsFromVm completed, handling the result. >2014-07-15 20:53:40,611 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-49) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type CreateAllSnapshotsFromVm succeeded, clearing tasks. >2014-07-15 20:53:40,622 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-49) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 441c17bc-f57d-44ba-b7e1-3c9faea5afeb >2014-07-15 20:53:40,623 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-49) START, SPMClearTaskVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, taskId = 441c17bc-f57d-44ba-b7e1-3c9faea5afeb), log id: 49f4ad25 >2014-07-15 20:53:40,626 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-49) START, HSMClearTaskVDSCommand(HostName = 10.35.160.41, HostId = 64f3caf5-d883-4e40-b0e5-e81e2bf7f781, taskId=441c17bc-f57d-44ba-b7e1-3c9faea5afeb), log id: 5e937e11 >2014-07-15 20:53:40,635 INFO [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (org.ovirt.thread.pool-4-thread-20) Lock freed to object EngineLock [exclusiveLocks= key: 75366289-1e23-4171-8df7-95945a59ab3c value: VM >, sharedLocks= ] >2014-07-15 20:53:40,664 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-4-thread-20) Correlation ID: 8ce54bc5-c1c4-4ab9_addSnapshot_create, Job ID: 954d23f7-5770-4c0c-a2cd-95c58a14aebb, Call Stack: null, Custom Event ID: -1, Message: Snapshot 'snapshot for vm vm_prealloc_8090' creation for VM 'vm_prealloc_8090' has been completed. >2014-07-15 20:53:40,664 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-20) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type CreateAllSnapshotsFromVm completed, handling the result. >2014-07-15 20:53:40,664 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-20) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type CreateAllSnapshotsFromVm succeeded, clearing tasks. >2014-07-15 20:53:40,674 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-49) FINISH, HSMClearTaskVDSCommand, log id: 5e937e11 >2014-07-15 20:53:40,675 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-49) FINISH, SPMClearTaskVDSCommand, log id: 49f4ad25 >2014-07-15 20:53:40,676 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-20) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 49c2f9b6-99ce-4d9a-82d3-655f45cd4677 >2014-07-15 20:53:40,676 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-20) START, SPMClearTaskVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, taskId = 49c2f9b6-99ce-4d9a-82d3-655f45cd4677), log id: 7bd725 >2014-07-15 20:53:40,677 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-49) BaseAsyncTask::RemoveTaskFromDB: Removed task 441c17bc-f57d-44ba-b7e1-3c9faea5afeb from DataBase >2014-07-15 20:53:40,677 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-49) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity be74faae-6915-4581-96b7-54af870eb889 >2014-07-15 20:53:40,679 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-20) START, HSMClearTaskVDSCommand(HostName = 10.35.160.41, HostId = 64f3caf5-d883-4e40-b0e5-e81e2bf7f781, taskId=49c2f9b6-99ce-4d9a-82d3-655f45cd4677), log id: 4269be62 >2014-07-15 20:53:40,776 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-20) FINISH, HSMClearTaskVDSCommand, log id: 4269be62 >2014-07-15 20:53:40,776 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-20) FINISH, SPMClearTaskVDSCommand, log id: 7bd725 >2014-07-15 20:53:40,779 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-20) BaseAsyncTask::RemoveTaskFromDB: Removed task 49c2f9b6-99ce-4d9a-82d3-655f45cd4677 from DataBase >2014-07-15 20:53:40,779 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-20) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 6f9e933d-8e93-440d-846f-7d2111cc3bf8 >2014-07-15 20:54:00,675 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-66) Setting new tasks map. The map contains now 3 tasks >2014-07-15 20:54:02,153 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-1) Running command: LoginUserCommand internal: false. >2014-07-15 20:54:02,162 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-1) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin logged in. >2014-07-15 20:54:02,462 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-3) Running command: LoginUserCommand internal: false. >2014-07-15 20:54:02,623 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Running command: LoginUserCommand internal: false. >2014-07-15 20:54:03,156 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Running command: LoginUserCommand internal: false. >2014-07-15 20:54:03,350 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Running command: LoginUserCommand internal: false. >2014-07-15 20:54:04,239 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Running command: LoginUserCommand internal: false. >2014-07-15 20:54:04,266 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-1) Running command: LoginUserCommand internal: false. >2014-07-15 20:54:04,357 INFO [org.ovirt.engine.core.bll.ExportVmCommand] (ajp-/127.0.0.1:8702-1) [2bd5115c-5753-4854_exportVm_syncAction] Lock Acquired to object EngineLock [exclusiveLocks= key: 75366289-1e23-4171-8df7-95945a59ab3c value: VM >, sharedLocks= ] >2014-07-15 20:54:04,407 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-1) [2bd5115c-5753-4854_exportVm_syncAction] START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = null), log id: 62da1bb9 >2014-07-15 20:54:04,468 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-1) [2bd5115c-5753-4854_exportVm_syncAction] FINISH, GetVmsInfoVDSCommand, log id: 62da1bb9 >2014-07-15 20:54:04,499 INFO [org.ovirt.engine.core.bll.ExportVmCommand] (ajp-/127.0.0.1:8702-1) [2bd5115c-5753-4854_exportVm_syncAction] Running command: ExportVmCommand internal: false. Entities affected : ID: 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b Type: Storage >2014-07-15 20:54:04,500 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-1) [2bd5115c-5753-4854_exportVm_syncAction] START, SetVmStatusVDSCommand( vmId = 75366289-1e23-4171-8df7-95945a59ab3c, status = ImageLocked, exit status = Normal), log id: 37fce6f0 >2014-07-15 20:54:04,516 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-1) [2bd5115c-5753-4854_exportVm_syncAction] FINISH, SetVmStatusVDSCommand, log id: 37fce6f0 >2014-07-15 20:54:04,520 INFO [org.ovirt.engine.core.bll.ExportVmCommand] (ajp-/127.0.0.1:8702-1) [2bd5115c-5753-4854_exportVm_syncAction] Lock freed to object EngineLock [exclusiveLocks= key: 75366289-1e23-4171-8df7-95945a59ab3c value: VM >, sharedLocks= ] >2014-07-15 20:54:04,526 INFO [org.ovirt.engine.core.bll.CopyImageGroupCommand] (ajp-/127.0.0.1:8702-1) [32891820] Running command: CopyImageGroupCommand internal: true. Entities affected : ID: 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b Type: Storage >2014-07-15 20:54:04,539 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.MoveImageGroupVDSCommand] (ajp-/127.0.0.1:8702-1) [32891820] START, MoveImageGroupVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 710a1b29-5037-435e-8f38-44edc78dc175, dstDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmId = 75366289-1e23-4171-8df7-95945a59ab3c, op = Copy, postZero = false, force = false), log id: 5b58f767 >2014-07-15 20:54:04,658 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.MoveImageGroupVDSCommand] (ajp-/127.0.0.1:8702-1) [32891820] FINISH, MoveImageGroupVDSCommand, log id: 5b58f767 >2014-07-15 20:54:04,670 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp-/127.0.0.1:8702-1) [32891820] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 731030c4-255b-472e-8f5b-ef5a505fcfbc >2014-07-15 20:54:04,670 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp-/127.0.0.1:8702-1) [32891820] CommandMultiAsyncTasks::AttachTask: Attaching task 3fed3a43-27af-4665-8980-6866e2ab531f to command 731030c4-255b-472e-8f5b-ef5a505fcfbc. >2014-07-15 20:54:04,681 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-1) [32891820] Adding task 3fed3a43-27af-4665-8980-6866e2ab531f (Parent Command ExportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-15 20:54:04,709 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-1) [32891820] Correlation ID: 2bd5115c-5753-4854_exportVm_syncAction, Job ID: 51dd5544-9117-4a59-b6fd-9c00c36fea87, Call Stack: null, Custom Event ID: -1, Message: Starting export Vm vm_prealloc_8090 to export_domain >2014-07-15 20:54:04,709 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-1) [32891820] BaseAsyncTask::startPollingTask: Starting to poll task 3fed3a43-27af-4665-8980-6866e2ab531f. >2014-07-15 20:54:10,378 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-90) Polling and updating Async Tasks: 4 tasks, 1 tasks to poll now >2014-07-15 20:54:10,418 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-90) SPMAsyncTask::PollTask: Polling task 3fed3a43-27af-4665-8980-6866e2ab531f (Parent Command ExportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status running. >2014-07-15 20:54:10,419 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-90) Finished polling Tasks, will poll again in 10 seconds. >2014-07-15 20:54:20,451 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-13) SPMAsyncTask::PollTask: Polling task 3fed3a43-27af-4665-8980-6866e2ab531f (Parent Command ExportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-15 20:54:20,458 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-13) BaseAsyncTask::OnTaskEndSuccess: Task 3fed3a43-27af-4665-8980-6866e2ab531f (Parent Command ExportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-15 20:54:20,460 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-13) CommandAsyncTask::EndActionIfNecessary: All tasks of command 731030c4-255b-472e-8f5b-ef5a505fcfbc has ended -> executing endAction >2014-07-15 20:54:20,461 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-13) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: 731030c4-255b-472e-8f5b-ef5a505fcfbc): calling endAction . >2014-07-15 20:54:20,461 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-22) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction ExportVm, executionIndex: 0 >2014-07-15 20:54:20,479 INFO [org.ovirt.engine.core.bll.ExportVmCommand] (org.ovirt.thread.pool-4-thread-22) [2bd5115c-5753-4854_exportVm_syncAction] Ending command successfully: org.ovirt.engine.core.bll.ExportVmCommand >2014-07-15 20:54:20,482 INFO [org.ovirt.engine.core.bll.CopyImageGroupCommand] (org.ovirt.thread.pool-4-thread-22) [32891820] Ending command successfully: org.ovirt.engine.core.bll.CopyImageGroupCommand >2014-07-15 20:54:20,484 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-4-thread-22) [32891820] START, GetImageInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 710a1b29-5037-435e-8f38-44edc78dc175, imageId = bbc130ad-11bb-48df-a4a7-a43be27c1801), log id: 37c62e1a >2014-07-15 20:54:20,553 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-4-thread-22) [32891820] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@e6e9e53b, log id: 37c62e1a >2014-07-15 20:54:20,622 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (org.ovirt.thread.pool-4-thread-22) START, UpdateVMVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, infoDictionary.size = 1), log id: 36704eb2 >2014-07-15 20:54:21,071 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-4) Running command: LoginUserCommand internal: false. >2014-07-15 20:54:21,097 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-4) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin logged in. >2014-07-15 20:54:21,232 INFO [org.ovirt.engine.core.bll.ExportVmCommand] (ajp-/127.0.0.1:8702-4) [4501a254-e764-4654_exportVm_syncAction] Lock Acquired to object EngineLock [exclusiveLocks= key: ea94cd0b-a6bf-43d2-9293-01e471867eee value: VM >, sharedLocks= ] >2014-07-15 20:54:21,254 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-4) [4501a254-e764-4654_exportVm_syncAction] START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = null), log id: 6fdbb2ee >2014-07-15 20:54:23,048 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (org.ovirt.thread.pool-4-thread-22) FINISH, UpdateVMVDSCommand, log id: 36704eb2 >2014-07-15 20:54:23,048 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (org.ovirt.thread.pool-4-thread-22) START, SetVmStatusVDSCommand( vmId = 75366289-1e23-4171-8df7-95945a59ab3c, status = Down, exit status = Normal), log id: aacefb2 >2014-07-15 20:54:23,097 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (org.ovirt.thread.pool-4-thread-22) FINISH, SetVmStatusVDSCommand, log id: aacefb2 >2014-07-15 20:54:23,123 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-4-thread-22) Correlation ID: 2bd5115c-5753-4854_exportVm_syncAction, Job ID: 51dd5544-9117-4a59-b6fd-9c00c36fea87, Call Stack: null, Custom Event ID: -1, Message: Vm vm_prealloc_8090 was exported successfully to export_domain >2014-07-15 20:54:23,123 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-22) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ExportVm completed, handling the result. >2014-07-15 20:54:23,124 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-22) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ExportVm succeeded, clearing tasks. >2014-07-15 20:54:23,131 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-22) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 3fed3a43-27af-4665-8980-6866e2ab531f >2014-07-15 20:54:23,131 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-22) START, SPMClearTaskVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, taskId = 3fed3a43-27af-4665-8980-6866e2ab531f), log id: 3712cf43 >2014-07-15 20:54:23,149 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-4) [4501a254-e764-4654_exportVm_syncAction] FINISH, GetVmsInfoVDSCommand, log id: 6fdbb2ee >2014-07-15 20:54:23,151 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-22) START, HSMClearTaskVDSCommand(HostName = 10.35.160.41, HostId = 64f3caf5-d883-4e40-b0e5-e81e2bf7f781, taskId=3fed3a43-27af-4665-8980-6866e2ab531f), log id: 33e553f4 >2014-07-15 20:54:23,194 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-22) FINISH, HSMClearTaskVDSCommand, log id: 33e553f4 >2014-07-15 20:54:23,194 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-22) FINISH, SPMClearTaskVDSCommand, log id: 3712cf43 >2014-07-15 20:54:23,197 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-22) BaseAsyncTask::RemoveTaskFromDB: Removed task 3fed3a43-27af-4665-8980-6866e2ab531f from DataBase >2014-07-15 20:54:23,197 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-22) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 731030c4-255b-472e-8f5b-ef5a505fcfbc >2014-07-15 20:54:23,352 INFO [org.ovirt.engine.core.bll.ExportVmCommand] (ajp-/127.0.0.1:8702-4) [4501a254-e764-4654_exportVm_syncAction] Running command: ExportVmCommand internal: false. Entities affected : ID: 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b Type: Storage >2014-07-15 20:54:23,352 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-4) [4501a254-e764-4654_exportVm_syncAction] START, SetVmStatusVDSCommand( vmId = ea94cd0b-a6bf-43d2-9293-01e471867eee, status = ImageLocked, exit status = Normal), log id: 54506e99 >2014-07-15 20:54:23,371 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-4) [4501a254-e764-4654_exportVm_syncAction] FINISH, SetVmStatusVDSCommand, log id: 54506e99 >2014-07-15 20:54:23,376 INFO [org.ovirt.engine.core.bll.ExportVmCommand] (ajp-/127.0.0.1:8702-4) [4501a254-e764-4654_exportVm_syncAction] Lock freed to object EngineLock [exclusiveLocks= key: ea94cd0b-a6bf-43d2-9293-01e471867eee value: VM >, sharedLocks= ] >2014-07-15 20:54:23,387 INFO [org.ovirt.engine.core.bll.CopyImageGroupCommand] (ajp-/127.0.0.1:8702-4) [f7aa47c] Running command: CopyImageGroupCommand internal: true. Entities affected : ID: 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b Type: Storage >2014-07-15 20:54:23,400 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.MoveImageGroupVDSCommand] (ajp-/127.0.0.1:8702-4) [f7aa47c] START, MoveImageGroupVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 31201085-616a-4d7f-8334-109d0491eae6, dstDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmId = ea94cd0b-a6bf-43d2-9293-01e471867eee, op = Copy, postZero = false, force = false), log id: 245d43df >2014-07-15 20:54:23,554 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.MoveImageGroupVDSCommand] (ajp-/127.0.0.1:8702-4) [f7aa47c] FINISH, MoveImageGroupVDSCommand, log id: 245d43df >2014-07-15 20:54:23,567 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp-/127.0.0.1:8702-4) [f7aa47c] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command eec8a96a-8e89-420d-9846-2397141e3e1f >2014-07-15 20:54:23,567 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp-/127.0.0.1:8702-4) [f7aa47c] CommandMultiAsyncTasks::AttachTask: Attaching task 8b48ad84-c1a7-46b5-b48d-a330ae557b69 to command eec8a96a-8e89-420d-9846-2397141e3e1f. >2014-07-15 20:54:23,577 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-4) [f7aa47c] Adding task 8b48ad84-c1a7-46b5-b48d-a330ae557b69 (Parent Command ExportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-15 20:54:23,606 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-4) [f7aa47c] Correlation ID: 4501a254-e764-4654_exportVm_syncAction, Job ID: 13e73ae1-ba85-43f5-b7f7-0ffe8b341096, Call Stack: null, Custom Event ID: -1, Message: Starting export Vm vm_thin_8090 to export_domain >2014-07-15 20:54:23,606 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-4) [f7aa47c] BaseAsyncTask::startPollingTask: Starting to poll task 8b48ad84-c1a7-46b5-b48d-a330ae557b69. >2014-07-15 20:54:30,462 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-31) Polling and updating Async Tasks: 5 tasks, 1 tasks to poll now >2014-07-15 20:54:30,490 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-31) SPMAsyncTask::PollTask: Polling task 8b48ad84-c1a7-46b5-b48d-a330ae557b69 (Parent Command ExportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-15 20:54:30,498 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-31) BaseAsyncTask::OnTaskEndSuccess: Task 8b48ad84-c1a7-46b5-b48d-a330ae557b69 (Parent Command ExportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-15 20:54:30,499 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-31) CommandAsyncTask::EndActionIfNecessary: All tasks of command eec8a96a-8e89-420d-9846-2397141e3e1f has ended -> executing endAction >2014-07-15 20:54:30,499 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-31) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: eec8a96a-8e89-420d-9846-2397141e3e1f): calling endAction . >2014-07-15 20:54:30,499 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-38) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction ExportVm, executionIndex: 0 >2014-07-15 20:54:30,516 INFO [org.ovirt.engine.core.bll.ExportVmCommand] (org.ovirt.thread.pool-4-thread-38) [4501a254-e764-4654_exportVm_syncAction] Ending command successfully: org.ovirt.engine.core.bll.ExportVmCommand >2014-07-15 20:54:30,519 INFO [org.ovirt.engine.core.bll.CopyImageGroupCommand] (org.ovirt.thread.pool-4-thread-38) [f7aa47c] Ending command successfully: org.ovirt.engine.core.bll.CopyImageGroupCommand >2014-07-15 20:54:30,521 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-4-thread-38) [f7aa47c] START, GetImageInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 31201085-616a-4d7f-8334-109d0491eae6, imageId = a12fab0f-1b8e-4ef9-99f7-ce15fa5e4a37), log id: 7c233e1 >2014-07-15 20:54:30,591 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-4-thread-38) [f7aa47c] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@ed802f35, log id: 7c233e1 >2014-07-15 20:54:30,661 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (org.ovirt.thread.pool-4-thread-38) START, UpdateVMVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, infoDictionary.size = 1), log id: 6fe81651 >2014-07-15 20:54:30,676 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-98) Setting new tasks map. The map contains now 4 tasks >2014-07-15 20:54:32,952 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (org.ovirt.thread.pool-4-thread-38) FINISH, UpdateVMVDSCommand, log id: 6fe81651 >2014-07-15 20:54:32,953 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (org.ovirt.thread.pool-4-thread-38) START, SetVmStatusVDSCommand( vmId = ea94cd0b-a6bf-43d2-9293-01e471867eee, status = Down, exit status = Normal), log id: 473f3bd2 >2014-07-15 20:54:32,969 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (org.ovirt.thread.pool-4-thread-38) FINISH, SetVmStatusVDSCommand, log id: 473f3bd2 >2014-07-15 20:54:33,003 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-4-thread-38) Correlation ID: 4501a254-e764-4654_exportVm_syncAction, Job ID: 13e73ae1-ba85-43f5-b7f7-0ffe8b341096, Call Stack: null, Custom Event ID: -1, Message: Vm vm_thin_8090 was exported successfully to export_domain >2014-07-15 20:54:33,004 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-38) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ExportVm completed, handling the result. >2014-07-15 20:54:33,004 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-38) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ExportVm succeeded, clearing tasks. >2014-07-15 20:54:33,010 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-38) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 8b48ad84-c1a7-46b5-b48d-a330ae557b69 >2014-07-15 20:54:33,010 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-38) START, SPMClearTaskVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, taskId = 8b48ad84-c1a7-46b5-b48d-a330ae557b69), log id: 647c55f3 >2014-07-15 20:54:33,013 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-38) START, HSMClearTaskVDSCommand(HostName = 10.35.160.41, HostId = 64f3caf5-d883-4e40-b0e5-e81e2bf7f781, taskId=8b48ad84-c1a7-46b5-b48d-a330ae557b69), log id: 1eeef80f >2014-07-15 20:54:33,049 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-38) FINISH, HSMClearTaskVDSCommand, log id: 1eeef80f >2014-07-15 20:54:33,049 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-38) FINISH, SPMClearTaskVDSCommand, log id: 647c55f3 >2014-07-15 20:54:33,052 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-38) BaseAsyncTask::RemoveTaskFromDB: Removed task 8b48ad84-c1a7-46b5-b48d-a330ae557b69 from DataBase >2014-07-15 20:54:33,052 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-38) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity eec8a96a-8e89-420d-9846-2397141e3e1f >2014-07-15 20:54:41,558 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Running command: LoginUserCommand internal: false. >2014-07-15 20:54:41,573 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-7) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin logged in. >2014-07-15 20:54:41,598 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (ajp-/127.0.0.1:8702-6) [d7799030-4585-44d9_removeVmAsynch_delete] Lock Acquired to object EngineLock [exclusiveLocks= key: ea94cd0b-a6bf-43d2-9293-01e471867eee value: VM >, sharedLocks= ] >2014-07-15 20:54:41,685 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (ajp-/127.0.0.1:8702-6) [d7799030-4585-44d9_removeVmAsynch_delete] Running command: RemoveVmCommand internal: false. Entities affected : ID: ea94cd0b-a6bf-43d2-9293-01e471867eee Type: VM >2014-07-15 20:54:41,686 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-6) [d7799030-4585-44d9_removeVmAsynch_delete] START, SetVmStatusVDSCommand( vmId = ea94cd0b-a6bf-43d2-9293-01e471867eee, status = ImageLocked, exit status = Normal), log id: 14ad512f >2014-07-15 20:54:41,703 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-6) [d7799030-4585-44d9_removeVmAsynch_delete] FINISH, SetVmStatusVDSCommand, log id: 14ad512f >2014-07-15 20:54:41,708 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (ajp-/127.0.0.1:8702-6) [d7799030-4585-44d9_removeVmAsynch_delete] Lock freed to object EngineLock [exclusiveLocks= key: ea94cd0b-a6bf-43d2-9293-01e471867eee value: VM >, sharedLocks= ] >2014-07-15 20:54:41,734 INFO [org.ovirt.engine.core.bll.RemoveAllVmImagesCommand] (ajp-/127.0.0.1:8702-6) [4196b3b1] Running command: RemoveAllVmImagesCommand internal: true. Entities affected : ID: ea94cd0b-a6bf-43d2-9293-01e471867eee Type: VM >2014-07-15 20:54:41,743 INFO [org.ovirt.engine.core.bll.RemoveImageCommand] (ajp-/127.0.0.1:8702-6) [1a5eb4d9] Running command: RemoveImageCommand internal: true. Entities affected : ID: 00000000-0000-0000-0000-000000000000 Type: Storage >2014-07-15 20:54:41,752 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (ajp-/127.0.0.1:8702-6) [1a5eb4d9] START, DeleteImageGroupVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 31201085-616a-4d7f-8334-109d0491eae6, postZeros = false, forceDelete = false), log id: 57890df9 >2014-07-15 20:54:41,871 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (ajp-/127.0.0.1:8702-6) [1a5eb4d9] FINISH, DeleteImageGroupVDSCommand, log id: 57890df9 >2014-07-15 20:54:41,888 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp-/127.0.0.1:8702-6) [1a5eb4d9] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 4e6a99da-821d-4802-851d-12c5b9787d72 >2014-07-15 20:54:41,888 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp-/127.0.0.1:8702-6) [1a5eb4d9] CommandMultiAsyncTasks::AttachTask: Attaching task 45333a6f-199a-4c08-885a-c03833eafa0b to command 4e6a99da-821d-4802-851d-12c5b9787d72. >2014-07-15 20:54:41,904 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-6) [1a5eb4d9] Adding task 45333a6f-199a-4c08-885a-c03833eafa0b (Parent Command RemoveVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-15 20:54:42,025 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-6) [1a5eb4d9] Correlation ID: d7799030-4585-44d9_removeVmAsynch_delete, Job ID: 224086d7-55b7-4098-83d6-c475a7da289a, Call Stack: null, Custom Event ID: -1, Message: VM vm_thin_8090 was successfully removed. >2014-07-15 20:54:42,025 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-6) [1a5eb4d9] BaseAsyncTask::startPollingTask: Starting to poll task 45333a6f-199a-4c08-885a-c03833eafa0b. >2014-07-15 20:54:42,198 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (ajp-/127.0.0.1:8702-4) [31a2d038-0c75-49c2_removeVmAsynch_delete] Lock Acquired to object EngineLock [exclusiveLocks= key: 75366289-1e23-4171-8df7-95945a59ab3c value: VM >, sharedLocks= ] >2014-07-15 20:54:42,254 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (ajp-/127.0.0.1:8702-4) [31a2d038-0c75-49c2_removeVmAsynch_delete] Running command: RemoveVmCommand internal: false. Entities affected : ID: 75366289-1e23-4171-8df7-95945a59ab3c Type: VM >2014-07-15 20:54:42,255 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-4) [31a2d038-0c75-49c2_removeVmAsynch_delete] START, SetVmStatusVDSCommand( vmId = 75366289-1e23-4171-8df7-95945a59ab3c, status = ImageLocked, exit status = Normal), log id: 5c86502b >2014-07-15 20:54:42,271 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-4) [31a2d038-0c75-49c2_removeVmAsynch_delete] FINISH, SetVmStatusVDSCommand, log id: 5c86502b >2014-07-15 20:54:42,276 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (ajp-/127.0.0.1:8702-4) [31a2d038-0c75-49c2_removeVmAsynch_delete] Lock freed to object EngineLock [exclusiveLocks= key: 75366289-1e23-4171-8df7-95945a59ab3c value: VM >, sharedLocks= ] >2014-07-15 20:54:42,305 INFO [org.ovirt.engine.core.bll.RemoveAllVmImagesCommand] (ajp-/127.0.0.1:8702-4) [113e2067] Running command: RemoveAllVmImagesCommand internal: true. Entities affected : ID: 75366289-1e23-4171-8df7-95945a59ab3c Type: VM >2014-07-15 20:54:42,315 INFO [org.ovirt.engine.core.bll.RemoveImageCommand] (ajp-/127.0.0.1:8702-4) [f372218] Running command: RemoveImageCommand internal: true. Entities affected : ID: 00000000-0000-0000-0000-000000000000 Type: Storage >2014-07-15 20:54:42,325 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (ajp-/127.0.0.1:8702-4) [f372218] START, DeleteImageGroupVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 710a1b29-5037-435e-8f38-44edc78dc175, postZeros = false, forceDelete = false), log id: 269738cb >2014-07-15 20:54:42,535 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (ajp-/127.0.0.1:8702-4) [f372218] FINISH, DeleteImageGroupVDSCommand, log id: 269738cb >2014-07-15 20:54:42,553 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp-/127.0.0.1:8702-4) [f372218] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 8b8355a0-553a-4bf2-b2fc-935191164527 >2014-07-15 20:54:42,553 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp-/127.0.0.1:8702-4) [f372218] CommandMultiAsyncTasks::AttachTask: Attaching task baf2474f-f32e-4f65-9004-105d193d5eb2 to command 8b8355a0-553a-4bf2-b2fc-935191164527. >2014-07-15 20:54:42,564 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-4) [f372218] Adding task baf2474f-f32e-4f65-9004-105d193d5eb2 (Parent Command RemoveVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-15 20:54:42,684 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-4) [f372218] Correlation ID: 31a2d038-0c75-49c2_removeVmAsynch_delete, Job ID: 8797455e-93e8-46c8-8875-ecd817eb7bc9, Call Stack: null, Custom Event ID: -1, Message: VM vm_prealloc_8090 was successfully removed. >2014-07-15 20:54:42,685 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-4) [f372218] BaseAsyncTask::startPollingTask: Starting to poll task baf2474f-f32e-4f65-9004-105d193d5eb2. >2014-07-15 20:54:43,295 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-2) START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = null), log id: 5e4790f3 >2014-07-15 20:54:43,342 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-1) Running command: LoginUserCommand internal: false. >2014-07-15 20:54:43,442 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-2) FINISH, GetVmsInfoVDSCommand, log id: 5e4790f3 >2014-07-15 20:54:43,696 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Running command: LoginUserCommand internal: false. >2014-07-15 20:54:43,730 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-6) START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = null), log id: 5873cba0 >2014-07-15 20:54:43,871 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-6) FINISH, GetVmsInfoVDSCommand, log id: 5873cba0 >2014-07-15 20:54:44,012 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-4) Running command: LoginUserCommand internal: false. >2014-07-15 20:54:44,055 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-4) START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = null), log id: 4b90f082 >2014-07-15 20:54:44,151 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-4) FINISH, GetVmsInfoVDSCommand, log id: 4b90f082 >2014-07-15 20:54:44,564 INFO [org.ovirt.engine.core.bll.ImportVmCommand] (ajp-/127.0.0.1:8702-4) [2881486c-7981-4b9c_importVm_syncAction] Lock Acquired to object EngineLock [exclusiveLocks= key: vm_thin_8090 value: VM_NAME >, sharedLocks= key: ea94cd0b-a6bf-43d2-9293-01e471867eee value: REMOTE_VM >] >2014-07-15 20:54:44,572 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-4) [2881486c-7981-4b9c_importVm_syncAction] START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = null), log id: 6f882941 >2014-07-15 20:54:44,670 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-4) [2881486c-7981-4b9c_importVm_syncAction] FINISH, GetVmsInfoVDSCommand, log id: 6f882941 >2014-07-15 20:54:45,007 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImagesListVDSCommand] (ajp-/127.0.0.1:8702-4) [2881486c-7981-4b9c_importVm_syncAction] START, GetImagesListVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, sdUUID = 8e09f329-8246-41b0-afd5-d214f2f7e86d), log id: 7cc1b625 >2014-07-15 20:54:45,042 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImagesListVDSCommand] (ajp-/127.0.0.1:8702-4) [2881486c-7981-4b9c_importVm_syncAction] FINISH, GetImagesListVDSCommand, return: [], log id: 7cc1b625 >2014-07-15 20:54:45,042 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DoesImageExistVDSCommand] (ajp-/127.0.0.1:8702-4) [2881486c-7981-4b9c_importVm_syncAction] START, DoesImageExistVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, imageGroupId = 31201085-616a-4d7f-8334-109d0491eae6, imageId = a12fab0f-1b8e-4ef9-99f7-ce15fa5e4a37), log id: 119d7720 >2014-07-15 20:54:45,111 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DoesImageExistVDSCommand] (ajp-/127.0.0.1:8702-4) [2881486c-7981-4b9c_importVm_syncAction] FINISH, DoesImageExistVDSCommand, return: true, log id: 119d7720 >2014-07-15 20:54:45,111 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DoesImageExistVDSCommand] (ajp-/127.0.0.1:8702-4) [2881486c-7981-4b9c_importVm_syncAction] START, DoesImageExistVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, imageGroupId = 31201085-616a-4d7f-8334-109d0491eae6, imageId = 18ed4347-ddb8-463e-a86f-9cb5859ad0ad), log id: 78f7dee8 >2014-07-15 20:54:45,180 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DoesImageExistVDSCommand] (ajp-/127.0.0.1:8702-4) [2881486c-7981-4b9c_importVm_syncAction] FINISH, DoesImageExistVDSCommand, return: true, log id: 78f7dee8 >2014-07-15 20:54:45,213 INFO [org.ovirt.engine.core.bll.ImportVmCommand] (ajp-/127.0.0.1:8702-4) [2881486c-7981-4b9c_importVm_syncAction] Running command: ImportVmCommand internal: false. Entities affected : ID: 8e09f329-8246-41b0-afd5-d214f2f7e86d Type: Storage >2014-07-15 20:54:45,319 INFO [org.ovirt.engine.core.bll.CopyImageGroupCommand] (ajp-/127.0.0.1:8702-4) [172e7231] Running command: CopyImageGroupCommand internal: true. Entities affected : ID: 8e09f329-8246-41b0-afd5-d214f2f7e86d Type: Storage >2014-07-15 20:54:45,343 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-4) [172e7231] START, CopyImageVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, imageGroupId = 31201085-616a-4d7f-8334-109d0491eae6, imageId = a12fab0f-1b8e-4ef9-99f7-ce15fa5e4a37, dstImageGroupId = 31201085-616a-4d7f-8334-109d0491eae6, vmId = ea94cd0b-a6bf-43d2-9293-01e471867eee, dstImageId = a12fab0f-1b8e-4ef9-99f7-ce15fa5e4a37, imageDescription = , dstStorageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, copyVolumeType = LeafVol, volumeFormat = COW, preallocate = Sparse, postZero = false, force = false), log id: 8157c20 >2014-07-15 20:54:45,343 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-4) [172e7231] -- executeIrsBrokerCommand: calling 'copyImage' with two new parameters: description and UUID >2014-07-15 20:54:45,343 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-4) [172e7231] -- copyImage parameters: > sdUUID=16759c2b-d1a7-48c1-9be5-9e3c1ad2574b > spUUID=ce09dc74-9c10-4da4-81a2-05104ed9c44e > vmGUID=ea94cd0b-a6bf-43d2-9293-01e471867eee > srcImageGUID=31201085-616a-4d7f-8334-109d0491eae6 > srcVolUUID=a12fab0f-1b8e-4ef9-99f7-ce15fa5e4a37 > dstImageGUID=31201085-616a-4d7f-8334-109d0491eae6 > dstVolUUID=a12fab0f-1b8e-4ef9-99f7-ce15fa5e4a37 > descr= > dstSdUUID=8e09f329-8246-41b0-afd5-d214f2f7e86d >2014-07-15 20:54:45,522 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-4) [172e7231] FINISH, CopyImageVDSCommand, return: 00000000-0000-0000-0000-000000000000, log id: 8157c20 >2014-07-15 20:54:45,547 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp-/127.0.0.1:8702-4) [172e7231] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 5fd0ca3e-11df-4246-90a0-39c86eea1f89 >2014-07-15 20:54:45,547 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp-/127.0.0.1:8702-4) [172e7231] CommandMultiAsyncTasks::AttachTask: Attaching task 54f1691c-5377-425d-a32e-895236001be4 to command 5fd0ca3e-11df-4246-90a0-39c86eea1f89. >2014-07-15 20:54:45,561 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-4) [172e7231] Adding task 54f1691c-5377-425d-a32e-895236001be4 (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-15 20:54:45,657 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-4) [172e7231] START, SetVmStatusVDSCommand( vmId = ea94cd0b-a6bf-43d2-9293-01e471867eee, status = ImageLocked, exit status = Normal), log id: 206abf72 >2014-07-15 20:54:45,674 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-4) [172e7231] FINISH, SetVmStatusVDSCommand, log id: 206abf72 >2014-07-15 20:54:45,695 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-4) [172e7231] Correlation ID: 2881486c-7981-4b9c_importVm_syncAction, Job ID: 1d667282-55e6-4e4c-80d0-20ef2bac5745, Call Stack: null, Custom Event ID: -1, Message: Starting to import Vm vm_thin_8090 to Data Center datacenter_nfsTestStorage, Cluster cluster_nfsTestStorage >2014-07-15 20:54:45,695 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-4) [172e7231] BaseAsyncTask::startPollingTask: Starting to poll task 54f1691c-5377-425d-a32e-895236001be4. >2014-07-15 20:54:50,501 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-70) Polling and updating Async Tasks: 7 tasks, 3 tasks to poll now >2014-07-15 20:54:50,538 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-70) SPMAsyncTask::PollTask: Polling task 54f1691c-5377-425d-a32e-895236001be4 (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-15 20:54:50,545 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-70) BaseAsyncTask::OnTaskEndSuccess: Task 54f1691c-5377-425d-a32e-895236001be4 (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-15 20:54:50,546 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-70) CommandAsyncTask::EndActionIfNecessary: All tasks of command 5fd0ca3e-11df-4246-90a0-39c86eea1f89 has ended -> executing endAction >2014-07-15 20:54:50,546 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-70) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: 5fd0ca3e-11df-4246-90a0-39c86eea1f89): calling endAction . >2014-07-15 20:54:50,546 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-70) SPMAsyncTask::PollTask: Polling task 45333a6f-199a-4c08-885a-c03833eafa0b (Parent Command RemoveVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-15 20:54:50,546 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-23) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction ImportVm, executionIndex: 0 >2014-07-15 20:54:50,557 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-70) BaseAsyncTask::OnTaskEndSuccess: Task 45333a6f-199a-4c08-885a-c03833eafa0b (Parent Command RemoveVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-15 20:54:50,564 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-70) CommandAsyncTask::EndActionIfNecessary: All tasks of command 4e6a99da-821d-4802-851d-12c5b9787d72 has ended -> executing endAction >2014-07-15 20:54:50,565 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-70) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: 4e6a99da-821d-4802-851d-12c5b9787d72): calling endAction . >2014-07-15 20:54:50,566 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-70) SPMAsyncTask::PollTask: Polling task baf2474f-f32e-4f65-9004-105d193d5eb2 (Parent Command RemoveVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-15 20:54:50,566 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-41) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction RemoveVm, executionIndex: 0 >2014-07-15 20:54:50,574 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-70) BaseAsyncTask::OnTaskEndSuccess: Task baf2474f-f32e-4f65-9004-105d193d5eb2 (Parent Command RemoveVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-15 20:54:50,576 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-70) CommandAsyncTask::EndActionIfNecessary: All tasks of command 8b8355a0-553a-4bf2-b2fc-935191164527 has ended -> executing endAction >2014-07-15 20:54:50,576 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-70) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: 8b8355a0-553a-4bf2-b2fc-935191164527): calling endAction . >2014-07-15 20:54:50,576 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-32) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction RemoveVm, executionIndex: 0 >2014-07-15 20:54:50,605 INFO [org.ovirt.engine.core.bll.ImportVmCommand] (org.ovirt.thread.pool-4-thread-23) [2881486c-7981-4b9c_importVm_syncAction] Ending command successfully: org.ovirt.engine.core.bll.ImportVmCommand >2014-07-15 20:54:50,609 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (org.ovirt.thread.pool-4-thread-41) [d7799030-4585-44d9_removeVmAsynch_delete] Ending command successfully: org.ovirt.engine.core.bll.RemoveVmCommand >2014-07-15 20:54:50,610 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (org.ovirt.thread.pool-4-thread-32) [31a2d038-0c75-49c2_removeVmAsynch_delete] Ending command successfully: org.ovirt.engine.core.bll.RemoveVmCommand >2014-07-15 20:54:50,613 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-41) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type RemoveVm completed, handling the result. >2014-07-15 20:54:50,613 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-41) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type RemoveVm succeeded, clearing tasks. >2014-07-15 20:54:50,615 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-32) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type RemoveVm completed, handling the result. >2014-07-15 20:54:50,615 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-32) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type RemoveVm succeeded, clearing tasks. >2014-07-15 20:54:50,618 INFO [org.ovirt.engine.core.bll.CopyImageGroupCommand] (org.ovirt.thread.pool-4-thread-23) [172e7231] Ending command successfully: org.ovirt.engine.core.bll.CopyImageGroupCommand >2014-07-15 20:54:50,624 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-41) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 45333a6f-199a-4c08-885a-c03833eafa0b >2014-07-15 20:54:50,624 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-41) START, SPMClearTaskVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, taskId = 45333a6f-199a-4c08-885a-c03833eafa0b), log id: 20ea88d7 >2014-07-15 20:54:50,624 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-32) SPMAsyncTask::ClearAsyncTask: Attempting to clear task baf2474f-f32e-4f65-9004-105d193d5eb2 >2014-07-15 20:54:50,625 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-32) START, SPMClearTaskVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, taskId = baf2474f-f32e-4f65-9004-105d193d5eb2), log id: 77d9f0c6 >2014-07-15 20:54:50,628 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-41) START, HSMClearTaskVDSCommand(HostName = 10.35.160.41, HostId = 64f3caf5-d883-4e40-b0e5-e81e2bf7f781, taskId=45333a6f-199a-4c08-885a-c03833eafa0b), log id: 5bdec23 >2014-07-15 20:54:50,648 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-4-thread-23) [172e7231] START, GetImageInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 31201085-616a-4d7f-8334-109d0491eae6, imageId = a12fab0f-1b8e-4ef9-99f7-ce15fa5e4a37), log id: 6f0cae17 >2014-07-15 20:54:50,669 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-41) FINISH, HSMClearTaskVDSCommand, log id: 5bdec23 >2014-07-15 20:54:50,669 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-41) FINISH, SPMClearTaskVDSCommand, log id: 20ea88d7 >2014-07-15 20:54:50,672 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-41) BaseAsyncTask::RemoveTaskFromDB: Removed task 45333a6f-199a-4c08-885a-c03833eafa0b from DataBase >2014-07-15 20:54:50,672 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-41) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 4e6a99da-821d-4802-851d-12c5b9787d72 >2014-07-15 20:54:50,746 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-4-thread-23) [172e7231] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@5905fc14, log id: 6f0cae17 >2014-07-15 20:54:50,749 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-32) START, HSMClearTaskVDSCommand(HostName = 10.35.160.41, HostId = 64f3caf5-d883-4e40-b0e5-e81e2bf7f781, taskId=baf2474f-f32e-4f65-9004-105d193d5eb2), log id: 778a3ff0 >2014-07-15 20:54:50,782 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (org.ovirt.thread.pool-4-thread-23) START, SetVmStatusVDSCommand( vmId = ea94cd0b-a6bf-43d2-9293-01e471867eee, status = Down, exit status = Normal), log id: 7c845931 >2014-07-15 20:54:50,794 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-32) FINISH, HSMClearTaskVDSCommand, log id: 778a3ff0 >2014-07-15 20:54:50,794 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-32) FINISH, SPMClearTaskVDSCommand, log id: 77d9f0c6 >2014-07-15 20:54:50,798 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-32) BaseAsyncTask::RemoveTaskFromDB: Removed task baf2474f-f32e-4f65-9004-105d193d5eb2 from DataBase >2014-07-15 20:54:50,799 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-32) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 8b8355a0-553a-4bf2-b2fc-935191164527 >2014-07-15 20:54:50,804 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (org.ovirt.thread.pool-4-thread-23) FINISH, SetVmStatusVDSCommand, log id: 7c845931 >2014-07-15 20:54:50,804 INFO [org.ovirt.engine.core.bll.ImportVmCommand] (org.ovirt.thread.pool-4-thread-23) Lock freed to object EngineLock [exclusiveLocks= key: vm_thin_8090 value: VM_NAME >, sharedLocks= key: ea94cd0b-a6bf-43d2-9293-01e471867eee value: REMOTE_VM >] >2014-07-15 20:54:50,822 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-4-thread-23) Correlation ID: 2881486c-7981-4b9c_importVm_syncAction, Job ID: 1d667282-55e6-4e4c-80d0-20ef2bac5745, Call Stack: null, Custom Event ID: -1, Message: Vm vm_thin_8090 was imported successfully to Data Center datacenter_nfsTestStorage, Cluster cluster_nfsTestStorage >2014-07-15 20:54:50,822 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-23) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ImportVm completed, handling the result. >2014-07-15 20:54:50,822 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-23) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ImportVm succeeded, clearing tasks. >2014-07-15 20:54:50,828 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-23) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 54f1691c-5377-425d-a32e-895236001be4 >2014-07-15 20:54:50,828 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-23) START, SPMClearTaskVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, taskId = 54f1691c-5377-425d-a32e-895236001be4), log id: 4a611954 >2014-07-15 20:54:50,830 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-23) START, HSMClearTaskVDSCommand(HostName = 10.35.160.41, HostId = 64f3caf5-d883-4e40-b0e5-e81e2bf7f781, taskId=54f1691c-5377-425d-a32e-895236001be4), log id: 2cc5fff2 >2014-07-15 20:54:50,870 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-23) FINISH, HSMClearTaskVDSCommand, log id: 2cc5fff2 >2014-07-15 20:54:50,870 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-23) FINISH, SPMClearTaskVDSCommand, log id: 4a611954 >2014-07-15 20:54:50,875 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-23) BaseAsyncTask::RemoveTaskFromDB: Removed task 54f1691c-5377-425d-a32e-895236001be4 from DataBase >2014-07-15 20:54:50,875 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-23) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 5fd0ca3e-11df-4246-90a0-39c86eea1f89 >2014-07-15 20:54:50,876 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-3) START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = null), log id: b336ef9 >2014-07-15 20:54:50,967 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-3) FINISH, GetVmsInfoVDSCommand, log id: b336ef9 >2014-07-15 20:54:51,368 INFO [org.ovirt.engine.core.bll.ImportVmCommand] (ajp-/127.0.0.1:8702-3) [b877be58-0fef-4440_importVm_syncAction] Lock Acquired to object EngineLock [exclusiveLocks= key: vm_prealloc_8090 value: VM_NAME >, sharedLocks= key: 75366289-1e23-4171-8df7-95945a59ab3c value: REMOTE_VM >] >2014-07-15 20:54:51,377 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-3) [b877be58-0fef-4440_importVm_syncAction] START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = null), log id: da2526e >2014-07-15 20:54:51,475 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-3) [b877be58-0fef-4440_importVm_syncAction] FINISH, GetVmsInfoVDSCommand, log id: da2526e >2014-07-15 20:54:51,806 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImagesListVDSCommand] (ajp-/127.0.0.1:8702-3) [b877be58-0fef-4440_importVm_syncAction] START, GetImagesListVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, sdUUID = 8e09f329-8246-41b0-afd5-d214f2f7e86d), log id: 71b3cab3 >2014-07-15 20:54:51,842 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImagesListVDSCommand] (ajp-/127.0.0.1:8702-3) [b877be58-0fef-4440_importVm_syncAction] FINISH, GetImagesListVDSCommand, return: [31201085-616a-4d7f-8334-109d0491eae6], log id: 71b3cab3 >2014-07-15 20:54:51,842 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DoesImageExistVDSCommand] (ajp-/127.0.0.1:8702-3) [b877be58-0fef-4440_importVm_syncAction] START, DoesImageExistVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, imageGroupId = 710a1b29-5037-435e-8f38-44edc78dc175, imageId = bbc130ad-11bb-48df-a4a7-a43be27c1801), log id: 52c0e816 >2014-07-15 20:54:51,912 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DoesImageExistVDSCommand] (ajp-/127.0.0.1:8702-3) [b877be58-0fef-4440_importVm_syncAction] FINISH, DoesImageExistVDSCommand, return: true, log id: 52c0e816 >2014-07-15 20:54:51,912 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DoesImageExistVDSCommand] (ajp-/127.0.0.1:8702-3) [b877be58-0fef-4440_importVm_syncAction] START, DoesImageExistVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, imageGroupId = 710a1b29-5037-435e-8f38-44edc78dc175, imageId = 15928c43-6024-4b76-aca3-18dd7c64bce1), log id: 76c526cb >2014-07-15 20:54:51,982 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DoesImageExistVDSCommand] (ajp-/127.0.0.1:8702-3) [b877be58-0fef-4440_importVm_syncAction] FINISH, DoesImageExistVDSCommand, return: true, log id: 76c526cb >2014-07-15 20:54:52,020 INFO [org.ovirt.engine.core.bll.ImportVmCommand] (ajp-/127.0.0.1:8702-3) [b877be58-0fef-4440_importVm_syncAction] Running command: ImportVmCommand internal: false. Entities affected : ID: 8e09f329-8246-41b0-afd5-d214f2f7e86d Type: Storage >2014-07-15 20:54:52,109 INFO [org.ovirt.engine.core.bll.CopyImageGroupCommand] (ajp-/127.0.0.1:8702-3) [6736cf9f] Running command: CopyImageGroupCommand internal: true. Entities affected : ID: 8e09f329-8246-41b0-afd5-d214f2f7e86d Type: Storage >2014-07-15 20:54:52,135 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-3) [6736cf9f] START, CopyImageVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, imageGroupId = 710a1b29-5037-435e-8f38-44edc78dc175, imageId = bbc130ad-11bb-48df-a4a7-a43be27c1801, dstImageGroupId = 710a1b29-5037-435e-8f38-44edc78dc175, vmId = 75366289-1e23-4171-8df7-95945a59ab3c, dstImageId = bbc130ad-11bb-48df-a4a7-a43be27c1801, imageDescription = , dstStorageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, copyVolumeType = LeafVol, volumeFormat = COW, preallocate = Sparse, postZero = false, force = false), log id: 77df5c0 >2014-07-15 20:54:52,135 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-3) [6736cf9f] -- executeIrsBrokerCommand: calling 'copyImage' with two new parameters: description and UUID >2014-07-15 20:54:52,135 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-3) [6736cf9f] -- copyImage parameters: > sdUUID=16759c2b-d1a7-48c1-9be5-9e3c1ad2574b > spUUID=ce09dc74-9c10-4da4-81a2-05104ed9c44e > vmGUID=75366289-1e23-4171-8df7-95945a59ab3c > srcImageGUID=710a1b29-5037-435e-8f38-44edc78dc175 > srcVolUUID=bbc130ad-11bb-48df-a4a7-a43be27c1801 > dstImageGUID=710a1b29-5037-435e-8f38-44edc78dc175 > dstVolUUID=bbc130ad-11bb-48df-a4a7-a43be27c1801 > descr= > dstSdUUID=8e09f329-8246-41b0-afd5-d214f2f7e86d >2014-07-15 20:54:52,350 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-3) [6736cf9f] FINISH, CopyImageVDSCommand, return: 00000000-0000-0000-0000-000000000000, log id: 77df5c0 >2014-07-15 20:54:52,365 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp-/127.0.0.1:8702-3) [6736cf9f] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 07338dd7-87d1-43a5-9547-3278cf941347 >2014-07-15 20:54:52,365 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp-/127.0.0.1:8702-3) [6736cf9f] CommandMultiAsyncTasks::AttachTask: Attaching task 26c32cb6-935e-4c2b-b05e-337f65b24d2b to command 07338dd7-87d1-43a5-9547-3278cf941347. >2014-07-15 20:54:52,378 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-3) [6736cf9f] Adding task 26c32cb6-935e-4c2b-b05e-337f65b24d2b (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-15 20:54:52,466 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-3) [6736cf9f] START, SetVmStatusVDSCommand( vmId = 75366289-1e23-4171-8df7-95945a59ab3c, status = ImageLocked, exit status = Normal), log id: 31ad7370 >2014-07-15 20:54:52,482 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-3) [6736cf9f] FINISH, SetVmStatusVDSCommand, log id: 31ad7370 >2014-07-15 20:54:52,502 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-3) [6736cf9f] Correlation ID: b877be58-0fef-4440_importVm_syncAction, Job ID: 679f481f-57cd-46d5-bf31-0588a1c498f0, Call Stack: null, Custom Event ID: -1, Message: Starting to import Vm vm_prealloc_8090 to Data Center datacenter_nfsTestStorage, Cluster cluster_nfsTestStorage >2014-07-15 20:54:52,502 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-3) [6736cf9f] BaseAsyncTask::startPollingTask: Starting to poll task 26c32cb6-935e-4c2b-b05e-337f65b24d2b. >2014-07-15 20:55:00,578 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-92) Polling and updating Async Tasks: 8 tasks, 1 tasks to poll now >2014-07-15 20:55:00,608 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-92) SPMAsyncTask::PollTask: Polling task 26c32cb6-935e-4c2b-b05e-337f65b24d2b (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status running. >2014-07-15 20:55:00,608 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-92) Finished polling Tasks, will poll again in 10 seconds. >2014-07-15 20:55:00,677 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-85) Setting new tasks map. The map contains now 6 tasks >2014-07-15 20:55:10,609 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-19) Polling and updating Async Tasks: 6 tasks, 1 tasks to poll now >2014-07-15 20:55:10,638 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-19) SPMAsyncTask::PollTask: Polling task 26c32cb6-935e-4c2b-b05e-337f65b24d2b (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-15 20:55:10,646 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-19) BaseAsyncTask::OnTaskEndSuccess: Task 26c32cb6-935e-4c2b-b05e-337f65b24d2b (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-15 20:55:10,647 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-19) CommandAsyncTask::EndActionIfNecessary: All tasks of command 07338dd7-87d1-43a5-9547-3278cf941347 has ended -> executing endAction >2014-07-15 20:55:10,647 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-19) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: 07338dd7-87d1-43a5-9547-3278cf941347): calling endAction . >2014-07-15 20:55:10,647 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-36) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction ImportVm, executionIndex: 0 >2014-07-15 20:55:10,665 INFO [org.ovirt.engine.core.bll.ImportVmCommand] (org.ovirt.thread.pool-4-thread-36) [b877be58-0fef-4440_importVm_syncAction] Ending command successfully: org.ovirt.engine.core.bll.ImportVmCommand >2014-07-15 20:55:10,670 INFO [org.ovirt.engine.core.bll.CopyImageGroupCommand] (org.ovirt.thread.pool-4-thread-36) [6736cf9f] Ending command successfully: org.ovirt.engine.core.bll.CopyImageGroupCommand >2014-07-15 20:55:10,680 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-4-thread-36) [6736cf9f] START, GetImageInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 710a1b29-5037-435e-8f38-44edc78dc175, imageId = bbc130ad-11bb-48df-a4a7-a43be27c1801), log id: 6f2c5c14 >2014-07-15 20:55:10,753 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-4-thread-36) [6736cf9f] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@2c82b272, log id: 6f2c5c14 >2014-07-15 20:55:10,768 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (org.ovirt.thread.pool-4-thread-36) START, SetVmStatusVDSCommand( vmId = 75366289-1e23-4171-8df7-95945a59ab3c, status = Down, exit status = Normal), log id: 20960d97 >2014-07-15 20:55:10,782 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (org.ovirt.thread.pool-4-thread-36) FINISH, SetVmStatusVDSCommand, log id: 20960d97 >2014-07-15 20:55:10,782 INFO [org.ovirt.engine.core.bll.ImportVmCommand] (org.ovirt.thread.pool-4-thread-36) Lock freed to object EngineLock [exclusiveLocks= key: vm_prealloc_8090 value: VM_NAME >, sharedLocks= key: 75366289-1e23-4171-8df7-95945a59ab3c value: REMOTE_VM >] >2014-07-15 20:55:10,799 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-4-thread-36) Correlation ID: b877be58-0fef-4440_importVm_syncAction, Job ID: 679f481f-57cd-46d5-bf31-0588a1c498f0, Call Stack: null, Custom Event ID: -1, Message: Vm vm_prealloc_8090 was imported successfully to Data Center datacenter_nfsTestStorage, Cluster cluster_nfsTestStorage >2014-07-15 20:55:10,799 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-36) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ImportVm completed, handling the result. >2014-07-15 20:55:10,799 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-36) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ImportVm succeeded, clearing tasks. >2014-07-15 20:55:10,805 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-36) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 26c32cb6-935e-4c2b-b05e-337f65b24d2b >2014-07-15 20:55:10,805 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-36) START, SPMClearTaskVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, taskId = 26c32cb6-935e-4c2b-b05e-337f65b24d2b), log id: 5f26faa >2014-07-15 20:55:10,808 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-36) START, HSMClearTaskVDSCommand(HostName = 10.35.160.41, HostId = 64f3caf5-d883-4e40-b0e5-e81e2bf7f781, taskId=26c32cb6-935e-4c2b-b05e-337f65b24d2b), log id: 6f8104f2 >2014-07-15 20:55:10,847 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-36) FINISH, HSMClearTaskVDSCommand, log id: 6f8104f2 >2014-07-15 20:55:10,847 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-36) FINISH, SPMClearTaskVDSCommand, log id: 5f26faa >2014-07-15 20:55:10,851 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-36) BaseAsyncTask::RemoveTaskFromDB: Removed task 26c32cb6-935e-4c2b-b05e-337f65b24d2b from DataBase >2014-07-15 20:55:10,851 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-36) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 07338dd7-87d1-43a5-9547-3278cf941347 >2014-07-15 20:55:20,824 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-8) START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = null), log id: b333c3a >2014-07-15 20:55:20,920 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-8) FINISH, GetVmsInfoVDSCommand, log id: b333c3a >2014-07-15 20:55:30,678 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-64) Setting new tasks map. The map contains now 4 tasks >2014-07-15 20:55:41,580 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-5) START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = null), log id: 38966741 >2014-07-15 20:55:41,671 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-5) FINISH, GetVmsInfoVDSCommand, log id: 38966741 >2014-07-15 20:55:44,004 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Running command: LoginUserCommand internal: false. >2014-07-15 20:55:44,013 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-8) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin logged in. >2014-07-15 20:55:44,047 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-5) Running command: LoginUserCommand internal: false. >2014-07-15 20:55:44,121 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (ajp-/127.0.0.1:8702-5) [57a1d621-b601-4e02_removeVmAsynch_delete] Lock Acquired to object EngineLock [exclusiveLocks= key: ea94cd0b-a6bf-43d2-9293-01e471867eee value: VM >, sharedLocks= ] >2014-07-15 20:55:44,159 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (ajp-/127.0.0.1:8702-5) [57a1d621-b601-4e02_removeVmAsynch_delete] Running command: RemoveVmCommand internal: false. Entities affected : ID: ea94cd0b-a6bf-43d2-9293-01e471867eee Type: VM >2014-07-15 20:55:44,159 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-5) [57a1d621-b601-4e02_removeVmAsynch_delete] START, SetVmStatusVDSCommand( vmId = ea94cd0b-a6bf-43d2-9293-01e471867eee, status = ImageLocked, exit status = Normal), log id: 812b343 >2014-07-15 20:55:44,178 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-5) [57a1d621-b601-4e02_removeVmAsynch_delete] FINISH, SetVmStatusVDSCommand, log id: 812b343 >2014-07-15 20:55:44,182 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (ajp-/127.0.0.1:8702-5) [57a1d621-b601-4e02_removeVmAsynch_delete] Lock freed to object EngineLock [exclusiveLocks= key: ea94cd0b-a6bf-43d2-9293-01e471867eee value: VM >, sharedLocks= ] >2014-07-15 20:55:44,209 INFO [org.ovirt.engine.core.bll.RemoveAllVmImagesCommand] (ajp-/127.0.0.1:8702-5) [da3031e] Running command: RemoveAllVmImagesCommand internal: true. Entities affected : ID: ea94cd0b-a6bf-43d2-9293-01e471867eee Type: VM >2014-07-15 20:55:44,212 INFO [org.ovirt.engine.core.bll.RemoveImageCommand] (ajp-/127.0.0.1:8702-5) [317f810b] Running command: RemoveImageCommand internal: true. Entities affected : ID: 00000000-0000-0000-0000-000000000000 Type: Storage >2014-07-15 20:55:44,222 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (ajp-/127.0.0.1:8702-5) [317f810b] START, DeleteImageGroupVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 31201085-616a-4d7f-8334-109d0491eae6, postZeros = false, forceDelete = false), log id: 6a727947 >2014-07-15 20:55:44,323 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (ajp-/127.0.0.1:8702-5) [317f810b] FINISH, DeleteImageGroupVDSCommand, log id: 6a727947 >2014-07-15 20:55:44,338 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp-/127.0.0.1:8702-5) [317f810b] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 1e2a7eb8-1f6d-4661-aff7-a48ba4604825 >2014-07-15 20:55:44,338 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp-/127.0.0.1:8702-5) [317f810b] CommandMultiAsyncTasks::AttachTask: Attaching task af63b79d-17f0-434b-a4f8-ca7db2ebae97 to command 1e2a7eb8-1f6d-4661-aff7-a48ba4604825. >2014-07-15 20:55:44,349 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-5) [317f810b] Adding task af63b79d-17f0-434b-a4f8-ca7db2ebae97 (Parent Command RemoveVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-15 20:55:44,439 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-5) [317f810b] Correlation ID: 57a1d621-b601-4e02_removeVmAsynch_delete, Job ID: 2eb842c0-c41e-4cea-9f8a-c57a878e4fc8, Call Stack: null, Custom Event ID: -1, Message: VM vm_thin_8090 was successfully removed. >2014-07-15 20:55:44,439 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-5) [317f810b] BaseAsyncTask::startPollingTask: Starting to poll task af63b79d-17f0-434b-a4f8-ca7db2ebae97. >2014-07-15 20:55:44,574 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (ajp-/127.0.0.1:8702-1) [50cbacd3-4b10-4a60_removeVmAsynch_delete] Lock Acquired to object EngineLock [exclusiveLocks= key: 75366289-1e23-4171-8df7-95945a59ab3c value: VM >, sharedLocks= ] >2014-07-15 20:55:44,611 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (ajp-/127.0.0.1:8702-1) [50cbacd3-4b10-4a60_removeVmAsynch_delete] Running command: RemoveVmCommand internal: false. Entities affected : ID: 75366289-1e23-4171-8df7-95945a59ab3c Type: VM >2014-07-15 20:55:44,611 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-1) [50cbacd3-4b10-4a60_removeVmAsynch_delete] START, SetVmStatusVDSCommand( vmId = 75366289-1e23-4171-8df7-95945a59ab3c, status = ImageLocked, exit status = Normal), log id: 7fd97c1 >2014-07-15 20:55:44,627 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-1) [50cbacd3-4b10-4a60_removeVmAsynch_delete] FINISH, SetVmStatusVDSCommand, log id: 7fd97c1 >2014-07-15 20:55:44,632 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (ajp-/127.0.0.1:8702-1) [50cbacd3-4b10-4a60_removeVmAsynch_delete] Lock freed to object EngineLock [exclusiveLocks= key: 75366289-1e23-4171-8df7-95945a59ab3c value: VM >, sharedLocks= ] >2014-07-15 20:55:44,657 INFO [org.ovirt.engine.core.bll.RemoveAllVmImagesCommand] (ajp-/127.0.0.1:8702-1) [7676cb67] Running command: RemoveAllVmImagesCommand internal: true. Entities affected : ID: 75366289-1e23-4171-8df7-95945a59ab3c Type: VM >2014-07-15 20:55:44,661 INFO [org.ovirt.engine.core.bll.RemoveImageCommand] (ajp-/127.0.0.1:8702-1) [41a47748] Running command: RemoveImageCommand internal: true. Entities affected : ID: 00000000-0000-0000-0000-000000000000 Type: Storage >2014-07-15 20:55:44,670 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (ajp-/127.0.0.1:8702-1) [41a47748] START, DeleteImageGroupVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 8e09f329-8246-41b0-afd5-d214f2f7e86d, imageGroupId = 710a1b29-5037-435e-8f38-44edc78dc175, postZeros = false, forceDelete = false), log id: 3e9650aa >2014-07-15 20:55:44,773 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (ajp-/127.0.0.1:8702-1) [41a47748] FINISH, DeleteImageGroupVDSCommand, log id: 3e9650aa >2014-07-15 20:55:44,791 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp-/127.0.0.1:8702-1) [41a47748] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 9a7541db-b9e4-4942-9abf-1592f1cdd265 >2014-07-15 20:55:44,793 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp-/127.0.0.1:8702-1) [41a47748] CommandMultiAsyncTasks::AttachTask: Attaching task 4f6fa713-0f4b-4581-8686-f8c494e5472b to command 9a7541db-b9e4-4942-9abf-1592f1cdd265. >2014-07-15 20:55:44,809 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-1) [41a47748] Adding task 4f6fa713-0f4b-4581-8686-f8c494e5472b (Parent Command RemoveVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-15 20:55:44,918 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-1) [41a47748] Correlation ID: 50cbacd3-4b10-4a60_removeVmAsynch_delete, Job ID: 4118c6ed-3d74-471a-8347-98af7e2e162e, Call Stack: null, Custom Event ID: -1, Message: VM vm_prealloc_8090 was successfully removed. >2014-07-15 20:55:44,918 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-1) [41a47748] BaseAsyncTask::startPollingTask: Starting to poll task 4f6fa713-0f4b-4581-8686-f8c494e5472b. >2014-07-15 20:55:45,882 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-6) START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = null), log id: 16681e58 >2014-07-15 20:55:45,973 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-6) FINISH, GetVmsInfoVDSCommand, log id: 16681e58 >2014-07-15 20:55:46,639 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-3) START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = null), log id: 22a6d329 >2014-07-15 20:55:46,755 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-3) FINISH, GetVmsInfoVDSCommand, log id: 22a6d329 >2014-07-15 20:55:47,110 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-3) [8d1efdd1-4224-41ca_removeVmFromExportDomain_delete] START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = [ea94cd0b-a6bf-43d2-9293-01e471867eee]), log id: 18869427 >2014-07-15 20:55:47,179 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-3) [8d1efdd1-4224-41ca_removeVmFromExportDomain_delete] FINISH, GetVmsInfoVDSCommand, log id: 18869427 >2014-07-15 20:55:47,358 INFO [org.ovirt.engine.core.bll.RemoveVmFromImportExportCommand] (ajp-/127.0.0.1:8702-3) [8d1efdd1-4224-41ca_removeVmFromExportDomain_delete] Lock Acquired to object EngineLock [exclusiveLocks= key: ea94cd0b-a6bf-43d2-9293-01e471867eee value: REMOTE_VM >, sharedLocks= ] >2014-07-15 20:55:47,378 INFO [org.ovirt.engine.core.bll.RemoveVmFromImportExportCommand] (ajp-/127.0.0.1:8702-3) [8d1efdd1-4224-41ca_removeVmFromExportDomain_delete] Running command: RemoveVmFromImportExportCommand internal: false. Entities affected : ID: 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b Type: Storage >2014-07-15 20:55:47,378 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.RemoveVMVDSCommand] (ajp-/127.0.0.1:8702-3) [8d1efdd1-4224-41ca_removeVmFromExportDomain_delete] START, RemoveVMVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmGuid = ea94cd0b-a6bf-43d2-9293-01e471867eee), log id: 22d7c42e >2014-07-15 20:55:47,430 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.RemoveVMVDSCommand] (ajp-/127.0.0.1:8702-3) [8d1efdd1-4224-41ca_removeVmFromExportDomain_delete] FINISH, RemoveVMVDSCommand, log id: 22d7c42e >2014-07-15 20:55:47,434 INFO [org.ovirt.engine.core.bll.RemoveAllVmImagesCommand] (ajp-/127.0.0.1:8702-3) [390b9e1] Running command: RemoveAllVmImagesCommand internal: true. Entities affected : ID: ea94cd0b-a6bf-43d2-9293-01e471867eee Type: VM >2014-07-15 20:55:47,439 INFO [org.ovirt.engine.core.bll.RemoveImageCommand] (ajp-/127.0.0.1:8702-3) [3e88b09] Running command: RemoveImageCommand internal: true. Entities affected : ID: 00000000-0000-0000-0000-000000000000 Type: Storage >2014-07-15 20:55:47,448 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (ajp-/127.0.0.1:8702-3) [3e88b09] START, DeleteImageGroupVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, imageGroupId = 31201085-616a-4d7f-8334-109d0491eae6, postZeros = false, forceDelete = false), log id: 3a48180e >2014-07-15 20:55:47,626 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (ajp-/127.0.0.1:8702-3) [3e88b09] FINISH, DeleteImageGroupVDSCommand, log id: 3a48180e >2014-07-15 20:55:47,643 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp-/127.0.0.1:8702-3) [3e88b09] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command eac97fcf-8f8d-4363-8ec0-e3bc50fe60a9 >2014-07-15 20:55:47,643 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp-/127.0.0.1:8702-3) [3e88b09] CommandMultiAsyncTasks::AttachTask: Attaching task 3035150b-02f6-4c6a-b49f-df2fcab6fccc to command eac97fcf-8f8d-4363-8ec0-e3bc50fe60a9. >2014-07-15 20:55:47,654 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-3) [3e88b09] Adding task 3035150b-02f6-4c6a-b49f-df2fcab6fccc (Parent Command RemoveVmFromImportExport, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-15 20:55:47,681 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-3) [3e88b09] Correlation ID: 8d1efdd1-4224-41ca_removeVmFromExportDomain_delete, Job ID: 537387e5-8788-4e5d-97d1-3633df8373ac, Call Stack: null, Custom Event ID: -1, Message: Vm vm_thin_8090 was removed from export_domain >2014-07-15 20:55:47,681 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-3) [3e88b09] BaseAsyncTask::startPollingTask: Starting to poll task 3035150b-02f6-4c6a-b49f-df2fcab6fccc. >2014-07-15 20:55:50,650 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-93) Polling and updating Async Tasks: 7 tasks, 3 tasks to poll now >2014-07-15 20:55:50,684 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-93) SPMAsyncTask::PollTask: Polling task 3035150b-02f6-4c6a-b49f-df2fcab6fccc (Parent Command RemoveVmFromImportExport, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-15 20:55:50,691 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-93) BaseAsyncTask::OnTaskEndSuccess: Task 3035150b-02f6-4c6a-b49f-df2fcab6fccc (Parent Command RemoveVmFromImportExport, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-15 20:55:50,692 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-93) CommandAsyncTask::EndActionIfNecessary: All tasks of command eac97fcf-8f8d-4363-8ec0-e3bc50fe60a9 has ended -> executing endAction >2014-07-15 20:55:50,692 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-93) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: eac97fcf-8f8d-4363-8ec0-e3bc50fe60a9): calling endAction . >2014-07-15 20:55:50,692 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-93) SPMAsyncTask::PollTask: Polling task 4f6fa713-0f4b-4581-8686-f8c494e5472b (Parent Command RemoveVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-15 20:55:50,692 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-19) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction RemoveVmFromImportExport, executionIndex: 0 >2014-07-15 20:55:50,703 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-93) BaseAsyncTask::OnTaskEndSuccess: Task 4f6fa713-0f4b-4581-8686-f8c494e5472b (Parent Command RemoveVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-15 20:55:50,704 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-93) CommandAsyncTask::EndActionIfNecessary: All tasks of command 9a7541db-b9e4-4942-9abf-1592f1cdd265 has ended -> executing endAction >2014-07-15 20:55:50,704 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-93) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: 9a7541db-b9e4-4942-9abf-1592f1cdd265): calling endAction . >2014-07-15 20:55:50,704 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-45) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction RemoveVm, executionIndex: 0 >2014-07-15 20:55:50,705 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-93) SPMAsyncTask::PollTask: Polling task af63b79d-17f0-434b-a4f8-ca7db2ebae97 (Parent Command RemoveVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-15 20:55:50,708 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (org.ovirt.thread.pool-4-thread-19) [8d1efdd1-4224-41ca_removeVmFromExportDomain_delete] START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = [ea94cd0b-a6bf-43d2-9293-01e471867eee]), log id: 70f0e11e >2014-07-15 20:55:50,737 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-93) BaseAsyncTask::OnTaskEndSuccess: Task af63b79d-17f0-434b-a4f8-ca7db2ebae97 (Parent Command RemoveVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-15 20:55:50,738 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-93) CommandAsyncTask::EndActionIfNecessary: All tasks of command 1e2a7eb8-1f6d-4661-aff7-a48ba4604825 has ended -> executing endAction >2014-07-15 20:55:50,739 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-93) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: 1e2a7eb8-1f6d-4661-aff7-a48ba4604825): calling endAction . >2014-07-15 20:55:50,739 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-25) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction RemoveVm, executionIndex: 0 >2014-07-15 20:55:50,771 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (org.ovirt.thread.pool-4-thread-45) [50cbacd3-4b10-4a60_removeVmAsynch_delete] Ending command successfully: org.ovirt.engine.core.bll.RemoveVmCommand >2014-07-15 20:55:50,781 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-45) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type RemoveVm completed, handling the result. >2014-07-15 20:55:50,781 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-45) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type RemoveVm succeeded, clearing tasks. >2014-07-15 20:55:50,782 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (org.ovirt.thread.pool-4-thread-19) [8d1efdd1-4224-41ca_removeVmFromExportDomain_delete] FINISH, GetVmsInfoVDSCommand, log id: 70f0e11e >2014-07-15 20:55:50,797 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-45) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 4f6fa713-0f4b-4581-8686-f8c494e5472b >2014-07-15 20:55:50,797 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-45) START, SPMClearTaskVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, taskId = 4f6fa713-0f4b-4581-8686-f8c494e5472b), log id: 49e8466e >2014-07-15 20:55:50,802 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-45) START, HSMClearTaskVDSCommand(HostName = 10.35.160.41, HostId = 64f3caf5-d883-4e40-b0e5-e81e2bf7f781, taskId=4f6fa713-0f4b-4581-8686-f8c494e5472b), log id: 306805aa >2014-07-15 20:55:50,805 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (org.ovirt.thread.pool-4-thread-19) [8d1efdd1-4224-41ca_removeVmFromExportDomain_delete] START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = [ea94cd0b-a6bf-43d2-9293-01e471867eee]), log id: 3dc93d4b >2014-07-15 20:55:50,807 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (org.ovirt.thread.pool-4-thread-25) [57a1d621-b601-4e02_removeVmAsynch_delete] Ending command successfully: org.ovirt.engine.core.bll.RemoveVmCommand >2014-07-15 20:55:50,822 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-25) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type RemoveVm completed, handling the result. >2014-07-15 20:55:50,822 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-25) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type RemoveVm succeeded, clearing tasks. >2014-07-15 20:55:50,833 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-25) SPMAsyncTask::ClearAsyncTask: Attempting to clear task af63b79d-17f0-434b-a4f8-ca7db2ebae97 >2014-07-15 20:55:50,833 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-25) START, SPMClearTaskVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, taskId = af63b79d-17f0-434b-a4f8-ca7db2ebae97), log id: 1548de75 >2014-07-15 20:55:50,842 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-45) FINISH, HSMClearTaskVDSCommand, log id: 306805aa >2014-07-15 20:55:50,842 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-45) FINISH, SPMClearTaskVDSCommand, log id: 49e8466e >2014-07-15 20:55:50,845 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-25) START, HSMClearTaskVDSCommand(HostName = 10.35.160.41, HostId = 64f3caf5-d883-4e40-b0e5-e81e2bf7f781, taskId=af63b79d-17f0-434b-a4f8-ca7db2ebae97), log id: 6304de3d >2014-07-15 20:55:50,847 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-45) BaseAsyncTask::RemoveTaskFromDB: Removed task 4f6fa713-0f4b-4581-8686-f8c494e5472b from DataBase >2014-07-15 20:55:50,847 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-45) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 9a7541db-b9e4-4942-9abf-1592f1cdd265 >2014-07-15 20:55:50,919 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-25) FINISH, HSMClearTaskVDSCommand, log id: 6304de3d >2014-07-15 20:55:50,919 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-25) FINISH, SPMClearTaskVDSCommand, log id: 1548de75 >2014-07-15 20:55:50,923 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-25) BaseAsyncTask::RemoveTaskFromDB: Removed task af63b79d-17f0-434b-a4f8-ca7db2ebae97 from DataBase >2014-07-15 20:55:50,923 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-25) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 1e2a7eb8-1f6d-4661-aff7-a48ba4604825 >2014-07-15 20:55:50,969 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (org.ovirt.thread.pool-4-thread-19) [8d1efdd1-4224-41ca_removeVmFromExportDomain_delete] FINISH, GetVmsInfoVDSCommand, log id: 3dc93d4b >2014-07-15 20:55:50,969 INFO [org.ovirt.engine.core.bll.RemoveVmFromImportExportCommand] (org.ovirt.thread.pool-4-thread-19) [8d1efdd1-4224-41ca_removeVmFromExportDomain_delete] Ending command successfully: org.ovirt.engine.core.bll.RemoveVmFromImportExportCommand >2014-07-15 20:55:50,970 INFO [org.ovirt.engine.core.bll.RemoveVmFromImportExportCommand] (org.ovirt.thread.pool-4-thread-19) [8d1efdd1-4224-41ca_removeVmFromExportDomain_delete] Lock freed to object EngineLock [exclusiveLocks= key: ea94cd0b-a6bf-43d2-9293-01e471867eee value: REMOTE_VM >, sharedLocks= ] >2014-07-15 20:55:50,970 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-19) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type RemoveVmFromImportExport completed, handling the result. >2014-07-15 20:55:50,970 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-19) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type RemoveVmFromImportExport succeeded, clearing tasks. >2014-07-15 20:55:50,976 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-19) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 3035150b-02f6-4c6a-b49f-df2fcab6fccc >2014-07-15 20:55:50,976 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-19) START, SPMClearTaskVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, taskId = 3035150b-02f6-4c6a-b49f-df2fcab6fccc), log id: 148f62f8 >2014-07-15 20:55:50,978 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-19) START, HSMClearTaskVDSCommand(HostName = 10.35.160.41, HostId = 64f3caf5-d883-4e40-b0e5-e81e2bf7f781, taskId=3035150b-02f6-4c6a-b49f-df2fcab6fccc), log id: 51aa9253 >2014-07-15 20:55:51,034 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-19) FINISH, HSMClearTaskVDSCommand, log id: 51aa9253 >2014-07-15 20:55:51,034 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-19) FINISH, SPMClearTaskVDSCommand, log id: 148f62f8 >2014-07-15 20:55:51,038 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-19) BaseAsyncTask::RemoveTaskFromDB: Removed task 3035150b-02f6-4c6a-b49f-df2fcab6fccc from DataBase >2014-07-15 20:55:51,038 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-19) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity eac97fcf-8f8d-4363-8ec0-e3bc50fe60a9 >2014-07-15 20:56:00,680 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-18) Setting new tasks map. The map contains now 4 tasks >2014-07-15 20:56:18,350 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-8) START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = null), log id: 35e7f131 >2014-07-15 20:56:18,446 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-8) FINISH, GetVmsInfoVDSCommand, log id: 35e7f131 >2014-07-15 20:56:19,104 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-5) START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = null), log id: 6d78c4e1 >2014-07-15 20:56:19,199 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-5) FINISH, GetVmsInfoVDSCommand, log id: 6d78c4e1 >2014-07-15 20:56:19,535 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-5) [b74a7842-0a64-4c17_removeVmFromExportDomain_delete] START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = [75366289-1e23-4171-8df7-95945a59ab3c]), log id: 221121ae >2014-07-15 20:56:19,696 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-5) [b74a7842-0a64-4c17_removeVmFromExportDomain_delete] FINISH, GetVmsInfoVDSCommand, log id: 221121ae >2014-07-15 20:56:20,077 INFO [org.ovirt.engine.core.bll.RemoveVmFromImportExportCommand] (ajp-/127.0.0.1:8702-5) [b74a7842-0a64-4c17_removeVmFromExportDomain_delete] Lock Acquired to object EngineLock [exclusiveLocks= key: 75366289-1e23-4171-8df7-95945a59ab3c value: REMOTE_VM >, sharedLocks= ] >2014-07-15 20:56:20,103 INFO [org.ovirt.engine.core.bll.RemoveVmFromImportExportCommand] (ajp-/127.0.0.1:8702-5) [b74a7842-0a64-4c17_removeVmFromExportDomain_delete] Running command: RemoveVmFromImportExportCommand internal: false. Entities affected : ID: 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b Type: Storage >2014-07-15 20:56:20,103 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.RemoveVMVDSCommand] (ajp-/127.0.0.1:8702-5) [b74a7842-0a64-4c17_removeVmFromExportDomain_delete] START, RemoveVMVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmGuid = 75366289-1e23-4171-8df7-95945a59ab3c), log id: 5f43c2f4 >2014-07-15 20:56:20,183 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.RemoveVMVDSCommand] (ajp-/127.0.0.1:8702-5) [b74a7842-0a64-4c17_removeVmFromExportDomain_delete] FINISH, RemoveVMVDSCommand, log id: 5f43c2f4 >2014-07-15 20:56:20,187 INFO [org.ovirt.engine.core.bll.RemoveAllVmImagesCommand] (ajp-/127.0.0.1:8702-5) [59b45208] Running command: RemoveAllVmImagesCommand internal: true. Entities affected : ID: 75366289-1e23-4171-8df7-95945a59ab3c Type: VM >2014-07-15 20:56:20,193 INFO [org.ovirt.engine.core.bll.RemoveImageCommand] (ajp-/127.0.0.1:8702-5) [657d62b8] Running command: RemoveImageCommand internal: true. Entities affected : ID: 00000000-0000-0000-0000-000000000000 Type: Storage >2014-07-15 20:56:20,205 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (ajp-/127.0.0.1:8702-5) [657d62b8] START, DeleteImageGroupVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, imageGroupId = 710a1b29-5037-435e-8f38-44edc78dc175, postZeros = false, forceDelete = false), log id: 31d72fde >2014-07-15 20:56:20,440 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (ajp-/127.0.0.1:8702-5) [657d62b8] FINISH, DeleteImageGroupVDSCommand, log id: 31d72fde >2014-07-15 20:56:20,481 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp-/127.0.0.1:8702-5) [657d62b8] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command af164b09-0d0e-42e7-a538-ba7f2aed7504 >2014-07-15 20:56:20,481 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp-/127.0.0.1:8702-5) [657d62b8] CommandMultiAsyncTasks::AttachTask: Attaching task d69dc39f-8ee6-462d-b70e-526e6f493a67 to command af164b09-0d0e-42e7-a538-ba7f2aed7504. >2014-07-15 20:56:20,496 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-5) [657d62b8] Adding task d69dc39f-8ee6-462d-b70e-526e6f493a67 (Parent Command RemoveVmFromImportExport, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-15 20:56:20,558 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-5) [657d62b8] Correlation ID: b74a7842-0a64-4c17_removeVmFromExportDomain_delete, Job ID: c6142cf4-ddec-4137-8e55-a1dceb23ecd1, Call Stack: null, Custom Event ID: -1, Message: Vm vm_prealloc_8090 was removed from export_domain >2014-07-15 20:56:20,558 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-5) [657d62b8] BaseAsyncTask::startPollingTask: Starting to poll task d69dc39f-8ee6-462d-b70e-526e6f493a67. >2014-07-15 20:56:20,743 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-63) Polling and updating Async Tasks: 5 tasks, 1 tasks to poll now >2014-07-15 20:56:20,772 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-63) SPMAsyncTask::PollTask: Polling task d69dc39f-8ee6-462d-b70e-526e6f493a67 (Parent Command RemoveVmFromImportExport, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-15 20:56:20,781 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-63) BaseAsyncTask::OnTaskEndSuccess: Task d69dc39f-8ee6-462d-b70e-526e6f493a67 (Parent Command RemoveVmFromImportExport, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-15 20:56:20,782 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-63) CommandAsyncTask::EndActionIfNecessary: All tasks of command af164b09-0d0e-42e7-a538-ba7f2aed7504 has ended -> executing endAction >2014-07-15 20:56:20,782 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-63) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: af164b09-0d0e-42e7-a538-ba7f2aed7504): calling endAction . >2014-07-15 20:56:20,782 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-4) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction RemoveVmFromImportExport, executionIndex: 0 >2014-07-15 20:56:20,794 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (org.ovirt.thread.pool-4-thread-4) [b74a7842-0a64-4c17_removeVmFromExportDomain_delete] START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = [75366289-1e23-4171-8df7-95945a59ab3c]), log id: 242d707 >2014-07-15 20:56:20,844 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (org.ovirt.thread.pool-4-thread-4) [b74a7842-0a64-4c17_removeVmFromExportDomain_delete] FINISH, GetVmsInfoVDSCommand, log id: 242d707 >2014-07-15 20:56:20,856 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (org.ovirt.thread.pool-4-thread-4) [b74a7842-0a64-4c17_removeVmFromExportDomain_delete] START, GetVmsInfoVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, storageDomainId = 16759c2b-d1a7-48c1-9be5-9e3c1ad2574b, vmIdList = [75366289-1e23-4171-8df7-95945a59ab3c]), log id: 2fce1800 >2014-07-15 20:56:20,905 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (org.ovirt.thread.pool-4-thread-4) [b74a7842-0a64-4c17_removeVmFromExportDomain_delete] FINISH, GetVmsInfoVDSCommand, log id: 2fce1800 >2014-07-15 20:56:20,905 INFO [org.ovirt.engine.core.bll.RemoveVmFromImportExportCommand] (org.ovirt.thread.pool-4-thread-4) [b74a7842-0a64-4c17_removeVmFromExportDomain_delete] Ending command successfully: org.ovirt.engine.core.bll.RemoveVmFromImportExportCommand >2014-07-15 20:56:20,905 INFO [org.ovirt.engine.core.bll.RemoveVmFromImportExportCommand] (org.ovirt.thread.pool-4-thread-4) [b74a7842-0a64-4c17_removeVmFromExportDomain_delete] Lock freed to object EngineLock [exclusiveLocks= key: 75366289-1e23-4171-8df7-95945a59ab3c value: REMOTE_VM >, sharedLocks= ] >2014-07-15 20:56:20,905 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-4) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type RemoveVmFromImportExport completed, handling the result. >2014-07-15 20:56:20,905 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-4) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type RemoveVmFromImportExport succeeded, clearing tasks. >2014-07-15 20:56:20,911 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-4) SPMAsyncTask::ClearAsyncTask: Attempting to clear task d69dc39f-8ee6-462d-b70e-526e6f493a67 >2014-07-15 20:56:20,912 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-4) START, SPMClearTaskVDSCommand( storagePoolId = ce09dc74-9c10-4da4-81a2-05104ed9c44e, ignoreFailoverLimit = false, taskId = d69dc39f-8ee6-462d-b70e-526e6f493a67), log id: 2173fc86 >2014-07-15 20:56:20,914 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-4) START, HSMClearTaskVDSCommand(HostName = 10.35.160.41, HostId = 64f3caf5-d883-4e40-b0e5-e81e2bf7f781, taskId=d69dc39f-8ee6-462d-b70e-526e6f493a67), log id: 45c7d292 >2014-07-15 20:56:20,947 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-4) FINISH, HSMClearTaskVDSCommand, log id: 45c7d292 >2014-07-15 20:56:20,948 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-4-thread-4) FINISH, SPMClearTaskVDSCommand, log id: 2173fc86 >2014-07-15 20:56:20,950 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-4) BaseAsyncTask::RemoveTaskFromDB: Removed task d69dc39f-8ee6-462d-b70e-526e6f493a67 from DataBase >2014-07-15 20:56:20,951 INFO [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-4-thread-4) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity af164b09-0d0e-42e7-a538-ba7f2aed7504 >2014-07-15 20:56:30,682 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-82) Setting new tasks map. The map contains now 4 tasks >2014-07-15 20:56:51,984 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Running command: LoginUserCommand internal: false. >2014-07-15 20:56:51,993 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-6) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin logged in. >2014-07-15 20:56:52,023 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-3) Running command: LoginUserCommand internal: false.
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=918355">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 1120087
: 918355