Attachment 913707 Details for Bug 1114903 – engine.log

engine.log

engine.log (text/x-log), 116.18 KB, created by Maor on 2014-07-01 08:56:19 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Maor

Created: 2014-07-01 08:56:19 UTC

Size: 116.18 KB

patch

obsolete

>2014-07-01 11:27:00,829 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (http--0.0.0.0-8080-2) [75a9a0ed] Lock Acquired to object EngineLock [exclusiveLocks= key: 7c38d42e-245e-41bd-b66d-25ba44dfc546 value: VM
>, sharedLocks= ]
>2014-07-01 11:27:01,020 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http--0.0.0.0-8080-2) [75a9a0ed] START, IsVmDuringInitiatingVDSCommand( vmId = 7c38d42e-245e-41bd-b66d-25ba44dfc546), log id: 5b0c51f8
>2014-07-01 11:27:01,021 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http--0.0.0.0-8080-2) [75a9a0ed] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 5b0c51f8
>2014-07-01 11:27:01,061 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (http--0.0.0.0-8080-2) [75a9a0ed] Candidate host venus-vdsa (95070901-19ea-4f27-9852-bb106cb88cb3) was filtered out by VAR__FILTERTYPE__INTERNAL filter Network
>2014-07-01 11:27:01,063 WARN  [org.ovirt.engine.core.bll.RunVmCommand] (http--0.0.0.0-8080-2) [75a9a0ed] CanDoAction of action RunVm failed. Reasons:VAR__ACTION__RUN,VAR__TYPE__VM,SCHEDULING_ALL_HOSTS_FILTERED_OUT,VAR__FILTERTYPE__INTERNAL,$hostName venus-vdsa,$filterName Network,$networkNames ,VAR__DETAIL__NETWORK_MISSING,SCHEDULING_HOST_FILTERED_REASON_WITH_DETAIL
>2014-07-01 11:27:01,064 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (http--0.0.0.0-8080-2) [75a9a0ed] Lock freed to object EngineLock [exclusiveLocks= key: 7c38d42e-245e-41bd-b66d-25ba44dfc546 value: VM
>, sharedLocks= ]
>2014-07-01 11:27:21,586 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (http--0.0.0.0-8080-2) [19a5f55] Lock Acquired to object EngineLock [exclusiveLocks= key: rnoriVM value: VM_NAME
>, sharedLocks= ]
>2014-07-01 11:27:21,654 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (http--0.0.0.0-8080-2) [19a5f55] Running command: AddVmFromScratchCommand internal: false. Entities affected :  ID: b2e953df-84eb-4383-bdd3-374d9ac4de97 Type: VdsGroups
>2014-07-01 11:27:22,114 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-2) [19a5f55] Correlation ID: 19a5f55, Job ID: bdd5e555-1927-4472-96d5-e748167e837a, Call Stack: null, Custom Event ID: -1, Message: VM rnoriVM was created by admin.
>2014-07-01 11:27:22,177 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (http--0.0.0.0-8080-2) [19a5f55] Lock freed to object EngineLock [exclusiveLocks= key: rnoriVM value: VM_NAME
>, sharedLocks= ]
>2014-07-01 11:27:23,450 INFO  [org.ovirt.engine.core.bll.network.vm.ReorderVmNicsCommand] (http--0.0.0.0-8080-4) [25409ce0] Running command: ReorderVmNicsCommand internal: false. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM
>2014-07-01 11:27:33,751 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-72) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:27:37,227 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (http--0.0.0.0-8080-2) [43c033cd] Lock Acquired to object EngineLock [exclusiveLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM_DISK_BOOT
>, sharedLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM
>]
>2014-07-01 11:27:37,277 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (http--0.0.0.0-8080-2) [43c033cd] Running command: AddDiskCommand internal: false. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM,  ID: 57f19479-c31a-4885-8529-1d0f1120a024 Type: Storage
>2014-07-01 11:27:37,406 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (http--0.0.0.0-8080-2) [222108e9] Running command: AddImageFromScratchCommand internal: true. Entities affected :  ID: 57f19479-c31a-4885-8529-1d0f1120a024 Type: Storage
>2014-07-01 11:27:37,438 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (http--0.0.0.0-8080-2) [222108e9] Lock freed to object EngineLock [exclusiveLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM_DISK_BOOT
>, sharedLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM
>]
>2014-07-01 11:27:37,439 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (http--0.0.0.0-8080-2) [222108e9] START, CreateImageVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, storageDomainId = 57f19479-c31a-4885-8529-1d0f1120a024, imageGroupId = 3c320707-752e-4364-9fc5-b489e0cccf97, imageSizeInBytes = 1073741824, volumeFormat = RAW, newImageId = e414e87f-b6e6-41d4-a5a5-df5c242e6185, newImageDescription = ), log id: 4d2203c6
>2014-07-01 11:27:37,446 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (http--0.0.0.0-8080-2) [222108e9] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID
>2014-07-01 11:27:37,876 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (http--0.0.0.0-8080-2) [222108e9] FINISH, CreateImageVDSCommand, return: e414e87f-b6e6-41d4-a5a5-df5c242e6185, log id: 4d2203c6
>2014-07-01 11:27:37,947 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (http--0.0.0.0-8080-2) [222108e9] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 6642c558-c257-44e4-bfb7-5345bff31321
>2014-07-01 11:27:37,948 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (http--0.0.0.0-8080-2) [222108e9] CommandMultiAsyncTasks::AttachTask: Attaching task 25e8c845-b59b-4569-9013-fbe556247f2c to command 6642c558-c257-44e4-bfb7-5345bff31321.
>2014-07-01 11:27:37,971 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (http--0.0.0.0-8080-2) [222108e9] Adding task 25e8c845-b59b-4569-9013-fbe556247f2c (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2014-07-01 11:27:38,105 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-2) [222108e9] Correlation ID: 43c033cd, Job ID: 082defd6-5c7b-4af7-a6aa-e1bb2d1f52f3, Call Stack: null, Custom Event ID: -1, Message: Add-Disk operation of rnoriVM_Disk1 was initiated on VM rnoriVM by admin.
>2014-07-01 11:27:38,106 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (http--0.0.0.0-8080-2) [222108e9] BaseAsyncTask::startPollingTask: Starting to poll task 25e8c845-b59b-4569-9013-fbe556247f2c.
>2014-07-01 11:27:46,622 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-45) Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
>2014-07-01 11:27:46,631 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-45) SPMAsyncTask::PollTask: Polling task 25e8c845-b59b-4569-9013-fbe556247f2c (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2014-07-01 11:27:46,656 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-45) BaseAsyncTask::OnTaskEndSuccess: Task 25e8c845-b59b-4569-9013-fbe556247f2c (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2014-07-01 11:27:46,657 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-45) CommandAsyncTask::EndActionIfNecessary: All tasks of command 6642c558-c257-44e4-bfb7-5345bff31321 has ended -> executing endAction
>2014-07-01 11:27:46,658 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-45) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: 6642c558-c257-44e4-bfb7-5345bff31321): calling endAction .
>2014-07-01 11:27:46,659 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-13) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction AddDisk, executionIndex: 0
>2014-07-01 11:27:46,739 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (org.ovirt.thread.pool-8-thread-13) [43c033cd] Ending command successfully: org.ovirt.engine.core.bll.AddDiskCommand
>2014-07-01 11:27:46,798 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (org.ovirt.thread.pool-8-thread-13) [222108e9] Ending command successfully: org.ovirt.engine.core.bll.AddImageFromScratchCommand
>2014-07-01 11:27:46,809 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-13) [222108e9] START, GetImageInfoVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, storageDomainId = 57f19479-c31a-4885-8529-1d0f1120a024, imageGroupId = 3c320707-752e-4364-9fc5-b489e0cccf97, imageId = e414e87f-b6e6-41d4-a5a5-df5c242e6185), log id: 5114292
>2014-07-01 11:27:46,854 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-13) [222108e9] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@d48289f0, log id: 5114292
>2014-07-01 11:27:46,881 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-13) Correlation ID: 43c033cd, Call Stack: null, Custom Event ID: -1, Message: The disk rnoriVM_Disk1 was successfully added to VM rnoriVM.
>2014-07-01 11:27:46,882 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-13) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk completed, handling the result.
>2014-07-01 11:27:46,883 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-13) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk succeeded, clearing tasks.
>2014-07-01 11:27:46,898 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-13) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 25e8c845-b59b-4569-9013-fbe556247f2c
>2014-07-01 11:27:46,899 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-13) START, SPMClearTaskVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, taskId = 25e8c845-b59b-4569-9013-fbe556247f2c), log id: 2110f10d
>2014-07-01 11:27:46,902 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-13) START, HSMClearTaskVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, taskId=25e8c845-b59b-4569-9013-fbe556247f2c), log id: 5f419d70
>2014-07-01 11:27:47,020 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-13) FINISH, HSMClearTaskVDSCommand, log id: 5f419d70
>2014-07-01 11:27:47,021 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-13) FINISH, SPMClearTaskVDSCommand, log id: 2110f10d
>2014-07-01 11:27:47,031 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-13) BaseAsyncTask::RemoveTaskFromDB: Removed task 25e8c845-b59b-4569-9013-fbe556247f2c from DataBase
>2014-07-01 11:27:47,032 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-13) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 6642c558-c257-44e4-bfb7-5345bff31321
>2014-07-01 11:27:48,276 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (http--0.0.0.0-8080-2) [3bd0f072] Lock Acquired to object EngineLock [exclusiveLocks= , sharedLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM
>]
>2014-07-01 11:27:48,323 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (http--0.0.0.0-8080-2) [3bd0f072] Running command: AddDiskCommand internal: false. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM,  ID: f0c702c0-aec8-49c2-a48c-0bf9e1fd447d Type: Storage
>2014-07-01 11:27:48,415 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (http--0.0.0.0-8080-2) [4c1ebf5c] Running command: AddImageFromScratchCommand internal: true. Entities affected :  ID: f0c702c0-aec8-49c2-a48c-0bf9e1fd447d Type: Storage
>2014-07-01 11:27:48,450 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (http--0.0.0.0-8080-2) [4c1ebf5c] Lock freed to object EngineLock [exclusiveLocks= , sharedLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM
>]
>2014-07-01 11:27:48,451 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (http--0.0.0.0-8080-2) [4c1ebf5c] START, CreateImageVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, storageDomainId = f0c702c0-aec8-49c2-a48c-0bf9e1fd447d, imageGroupId = 60b052a9-8ca8-4855-ad23-efa9e79aab48, imageSizeInBytes = 1073741824, volumeFormat = RAW, newImageId = 9792688f-a570-4ff4-98ca-911b6921b3e0, newImageDescription = ), log id: 731a888e
>2014-07-01 11:27:48,454 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (http--0.0.0.0-8080-2) [4c1ebf5c] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID
>2014-07-01 11:27:49,031 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (http--0.0.0.0-8080-2) [4c1ebf5c] FINISH, CreateImageVDSCommand, return: 9792688f-a570-4ff4-98ca-911b6921b3e0, log id: 731a888e
>2014-07-01 11:27:49,059 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (http--0.0.0.0-8080-2) [4c1ebf5c] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command e8fd01f5-47e1-4db6-bb52-a9a6aa4b0663
>2014-07-01 11:27:49,060 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (http--0.0.0.0-8080-2) [4c1ebf5c] CommandMultiAsyncTasks::AttachTask: Attaching task bccc8e81-8622-47bc-ab02-acd96bd83487 to command e8fd01f5-47e1-4db6-bb52-a9a6aa4b0663.
>2014-07-01 11:27:49,090 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (http--0.0.0.0-8080-2) [4c1ebf5c] Adding task bccc8e81-8622-47bc-ab02-acd96bd83487 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2014-07-01 11:27:49,132 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-2) [4c1ebf5c] Correlation ID: 3bd0f072, Job ID: 695f1d69-c472-40d1-a1d6-a57418084ea8, Call Stack: null, Custom Event ID: -1, Message: Add-Disk operation of rnoriVM_Disk2 was initiated on VM rnoriVM by admin.
>2014-07-01 11:27:49,133 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (http--0.0.0.0-8080-2) [4c1ebf5c] BaseAsyncTask::startPollingTask: Starting to poll task bccc8e81-8622-47bc-ab02-acd96bd83487.
>2014-07-01 11:27:56,660 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-73) Polling and updating Async Tasks: 2 tasks, 1 tasks to poll now
>2014-07-01 11:27:56,671 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-73) SPMAsyncTask::PollTask: Polling task bccc8e81-8622-47bc-ab02-acd96bd83487 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2014-07-01 11:27:56,692 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-73) BaseAsyncTask::OnTaskEndSuccess: Task bccc8e81-8622-47bc-ab02-acd96bd83487 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2014-07-01 11:27:56,693 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-73) CommandAsyncTask::EndActionIfNecessary: All tasks of command e8fd01f5-47e1-4db6-bb52-a9a6aa4b0663 has ended -> executing endAction
>2014-07-01 11:27:56,694 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-73) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: e8fd01f5-47e1-4db6-bb52-a9a6aa4b0663): calling endAction .
>2014-07-01 11:27:56,697 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-37) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction AddDisk, executionIndex: 0
>2014-07-01 11:27:56,725 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (org.ovirt.thread.pool-8-thread-37) [3bd0f072] Ending command successfully: org.ovirt.engine.core.bll.AddDiskCommand
>2014-07-01 11:27:56,792 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (org.ovirt.thread.pool-8-thread-37) [4c1ebf5c] Ending command successfully: org.ovirt.engine.core.bll.AddImageFromScratchCommand
>2014-07-01 11:27:56,800 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-37) [4c1ebf5c] START, GetImageInfoVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, storageDomainId = f0c702c0-aec8-49c2-a48c-0bf9e1fd447d, imageGroupId = 60b052a9-8ca8-4855-ad23-efa9e79aab48, imageId = 9792688f-a570-4ff4-98ca-911b6921b3e0), log id: 7c718ebd
>2014-07-01 11:27:56,841 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-37) [4c1ebf5c] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@9b5c1971, log id: 7c718ebd
>2014-07-01 11:27:56,866 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-37) Correlation ID: 3bd0f072, Call Stack: null, Custom Event ID: -1, Message: The disk rnoriVM_Disk2 was successfully added to VM rnoriVM.
>2014-07-01 11:27:56,867 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-37) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk completed, handling the result.
>2014-07-01 11:27:56,870 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-37) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk succeeded, clearing tasks.
>2014-07-01 11:27:56,883 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-37) SPMAsyncTask::ClearAsyncTask: Attempting to clear task bccc8e81-8622-47bc-ab02-acd96bd83487
>2014-07-01 11:27:56,884 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-37) START, SPMClearTaskVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, taskId = bccc8e81-8622-47bc-ab02-acd96bd83487), log id: 2da1e440
>2014-07-01 11:27:56,887 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-37) START, HSMClearTaskVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, taskId=bccc8e81-8622-47bc-ab02-acd96bd83487), log id: 5f903d27
>2014-07-01 11:27:57,059 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-37) FINISH, HSMClearTaskVDSCommand, log id: 5f903d27
>2014-07-01 11:27:57,060 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-37) FINISH, SPMClearTaskVDSCommand, log id: 2da1e440
>2014-07-01 11:27:57,091 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-37) BaseAsyncTask::RemoveTaskFromDB: Removed task bccc8e81-8622-47bc-ab02-acd96bd83487 from DataBase
>2014-07-01 11:27:57,092 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-37) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity e8fd01f5-47e1-4db6-bb52-a9a6aa4b0663
>2014-07-01 11:28:35,355 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-26) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:28:35,663 INFO  [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (DefaultQuartzScheduler_Worker-26) [6f766817] Lock Acquired to object EngineLock [exclusiveLocks= key: f0c702c0-aec8-49c2-a48c-0bf9e1fd447d value: STORAGE
>key: 9a55a473-c481-4526-a26c-bf793c644fef value: DISK
>key: 1045141f-4321-4573-a40e-d9d455b2c624 value: DISK
>, sharedLocks= key: af2c9167-5d67-4494-ae09-def33cf383c2 value: OVF_UPDATE
>]
>2014-07-01 11:28:35,667 INFO  [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (DefaultQuartzScheduler_Worker-26) [6f766817] Running command: ProcessOvfUpdateForStorageDomainCommand internal: true. Entities affected :  ID: f0c702c0-aec8-49c2-a48c-0bf9e1fd447d Type: Storage
>2014-07-01 11:28:35,849 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [6f766817] START, SetVolumeDescriptionVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, storageDomainId = f0c702c0-aec8-49c2-a48c-0bf9e1fd447d, imageGroupId = 9a55a473-c481-4526-a26c-bf793c644fef, imageId = d894b1dd-2b9e-49f8-90c6-4924e9067743), log id: 29f92b6b
>2014-07-01 11:28:35,852 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [6f766817] -- executeIrsBrokerCommand: calling 'setVolumeDescription' 
>2014-07-01 11:28:35,853 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [6f766817] -- setVolumeDescription parameters:
>                spUUID=af2c9167-5d67-4494-ae09-def33cf383c2
>                sdUUID=f0c702c0-aec8-49c2-a48c-0bf9e1fd447d
>                imageGroupGUID=9a55a473-c481-4526-a26c-bf793c644fef
>                volUUID=d894b1dd-2b9e-49f8-90c6-4924e9067743
>                description={"Updated":false,"Disk Description":"OVF_STORE","Storage Domains":[{"uuid":"f0c702c0-aec8-49c2-a48c-0bf9e1fd447d"}],"Last Updated":"Thu Jun 19 10:04:29 IDT 2014"}
>
>2014-07-01 11:28:36,026 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [6f766817] FINISH, SetVolumeDescriptionVDSCommand, log id: 29f92b6b
>2014-07-01 11:28:36,117 INFO  [org.ovirt.engine.core.bll.UploadStreamCommand] (DefaultQuartzScheduler_Worker-26) [561f385c] Running command: UploadStreamCommand internal: true. Entities affected :  ID: f0c702c0-aec8-49c2-a48c-0bf9e1fd447d Type: Storage
>2014-07-01 11:28:36,123 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-26) [561f385c] START, UploadStreamVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3), log id: 98786b1
>2014-07-01 11:28:36,125 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-26) [561f385c] -- executeVdsBrokerCommand: 
>2014-07-01 11:28:36,127 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-26) [561f385c] -- parameters:
>                spUUID=af2c9167-5d67-4494-ae09-def33cf383c2
>                sdUUID=f0c702c0-aec8-49c2-a48c-0bf9e1fd447d
>                imageGUID=9a55a473-c481-4526-a26c-bf793c644fef
>                volUUID=d894b1dd-2b9e-49f8-90c6-4924e9067743
>                size=225,280
>
>2014-07-01 11:28:38,072 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-26) [561f385c] FINISH, UploadStreamVDSCommand, log id: 98786b1
>2014-07-01 11:28:38,075 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-26) [561f385c] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 63996fbc-7b84-4d8b-9cc1-da3b7ced955a
>2014-07-01 11:28:38,076 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-26) [561f385c] CommandMultiAsyncTasks::AttachTask: Attaching task 381dbe8c-75d1-45ba-8a0b-72647163139a to command 63996fbc-7b84-4d8b-9cc1-da3b7ced955a.
>2014-07-01 11:28:38,105 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-26) [561f385c] Adding task 381dbe8c-75d1-45ba-8a0b-72647163139a (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2014-07-01 11:28:38,107 INFO  [org.ovirt.engine.core.bll.UploadStreamCommand] (DefaultQuartzScheduler_Worker-26) [561f385c] Lock freed to object EngineLock [exclusiveLocks= key: f0c702c0-aec8-49c2-a48c-0bf9e1fd447d value: STORAGE
>key: 9a55a473-c481-4526-a26c-bf793c644fef value: DISK
>key: 1045141f-4321-4573-a40e-d9d455b2c624 value: DISK
>, sharedLocks= key: af2c9167-5d67-4494-ae09-def33cf383c2 value: OVF_UPDATE
>]
>2014-07-01 11:28:38,114 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [561f385c] START, SetVolumeDescriptionVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, storageDomainId = f0c702c0-aec8-49c2-a48c-0bf9e1fd447d, imageGroupId = 9a55a473-c481-4526-a26c-bf793c644fef, imageId = d894b1dd-2b9e-49f8-90c6-4924e9067743), log id: 653cf1ed
>2014-07-01 11:28:38,116 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [561f385c] -- executeIrsBrokerCommand: calling 'setVolumeDescription' 
>2014-07-01 11:28:38,116 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [561f385c] -- setVolumeDescription parameters:
>                spUUID=af2c9167-5d67-4494-ae09-def33cf383c2
>                sdUUID=f0c702c0-aec8-49c2-a48c-0bf9e1fd447d
>                imageGroupGUID=9a55a473-c481-4526-a26c-bf793c644fef
>                volUUID=d894b1dd-2b9e-49f8-90c6-4924e9067743
>                description={"Updated":true,"Disk Description":"OVF_STORE","Storage Domains":[{"uuid":"f0c702c0-aec8-49c2-a48c-0bf9e1fd447d"}],"Last Updated":"Tue Jul 01 11:28:35 IDT 2014","Size":225280}
>
>2014-07-01 11:28:38,289 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [561f385c] FINISH, SetVolumeDescriptionVDSCommand, log id: 653cf1ed
>2014-07-01 11:28:38,317 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [561f385c] START, SetVolumeDescriptionVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, storageDomainId = f0c702c0-aec8-49c2-a48c-0bf9e1fd447d, imageGroupId = 1045141f-4321-4573-a40e-d9d455b2c624, imageId = 1fda29a1-0cf5-4b04-b4b9-80b5e97dce66), log id: 722ff858
>2014-07-01 11:28:38,317 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [561f385c] -- executeIrsBrokerCommand: calling 'setVolumeDescription' 
>2014-07-01 11:28:38,318 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [561f385c] -- setVolumeDescription parameters:
>                spUUID=af2c9167-5d67-4494-ae09-def33cf383c2
>                sdUUID=f0c702c0-aec8-49c2-a48c-0bf9e1fd447d
>                imageGroupGUID=1045141f-4321-4573-a40e-d9d455b2c624
>                volUUID=1fda29a1-0cf5-4b04-b4b9-80b5e97dce66
>                description={"Updated":false,"Disk Description":"OVF_STORE","Storage Domains":[{"uuid":"f0c702c0-aec8-49c2-a48c-0bf9e1fd447d"}],"Last Updated":"Thu Jun 19 10:04:29 IDT 2014"}
>
>2014-07-01 11:28:38,496 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [561f385c] FINISH, SetVolumeDescriptionVDSCommand, log id: 722ff858
>2014-07-01 11:28:38,600 INFO  [org.ovirt.engine.core.bll.UploadStreamCommand] (DefaultQuartzScheduler_Worker-26) [63518f31] Running command: UploadStreamCommand internal: true. Entities affected :  ID: f0c702c0-aec8-49c2-a48c-0bf9e1fd447d Type: Storage
>2014-07-01 11:28:38,602 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-26) [63518f31] START, UploadStreamVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3), log id: 433b2a0e
>2014-07-01 11:28:38,603 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-26) [63518f31] -- executeVdsBrokerCommand: 
>2014-07-01 11:28:38,603 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-26) [63518f31] -- parameters:
>                spUUID=af2c9167-5d67-4494-ae09-def33cf383c2
>                sdUUID=f0c702c0-aec8-49c2-a48c-0bf9e1fd447d
>                imageGUID=1045141f-4321-4573-a40e-d9d455b2c624
>                volUUID=1fda29a1-0cf5-4b04-b4b9-80b5e97dce66
>                size=225,280
>
>2014-07-01 11:28:40,647 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-26) [63518f31] FINISH, UploadStreamVDSCommand, log id: 433b2a0e
>2014-07-01 11:28:40,656 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-26) [63518f31] CommandMultiAsyncTasks::AttachTask: Attaching task ea520d28-f522-46e6-90c6-92281522beb1 to command 63996fbc-7b84-4d8b-9cc1-da3b7ced955a.
>2014-07-01 11:28:40,706 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-26) [63518f31] Adding task ea520d28-f522-46e6-90c6-92281522beb1 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2014-07-01 11:28:40,707 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler_Worker-26) [63518f31] Trying to release a shared lock for key: af2c9167-5d67-4494-ae09-def33cf383c2OVF_UPDATE , but lock does not exist
>2014-07-01 11:28:40,708 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler_Worker-26) [63518f31] Trying to release exclusive lock which does not exist, lock key: f0c702c0-aec8-49c2-a48c-0bf9e1fd447dSTORAGE
>2014-07-01 11:28:40,709 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler_Worker-26) [63518f31] Trying to release exclusive lock which does not exist, lock key: 9a55a473-c481-4526-a26c-bf793c644fefDISK
>2014-07-01 11:28:40,709 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler_Worker-26) [63518f31] Trying to release exclusive lock which does not exist, lock key: 1045141f-4321-4573-a40e-d9d455b2c624DISK
>2014-07-01 11:28:40,710 INFO  [org.ovirt.engine.core.bll.UploadStreamCommand] (DefaultQuartzScheduler_Worker-26) [63518f31] Lock freed to object EngineLock [exclusiveLocks= key: f0c702c0-aec8-49c2-a48c-0bf9e1fd447d value: STORAGE
>key: 9a55a473-c481-4526-a26c-bf793c644fef value: DISK
>key: 1045141f-4321-4573-a40e-d9d455b2c624 value: DISK
>, sharedLocks= key: af2c9167-5d67-4494-ae09-def33cf383c2 value: OVF_UPDATE
>]
>2014-07-01 11:28:40,712 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [63518f31] START, SetVolumeDescriptionVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, storageDomainId = f0c702c0-aec8-49c2-a48c-0bf9e1fd447d, imageGroupId = 1045141f-4321-4573-a40e-d9d455b2c624, imageId = 1fda29a1-0cf5-4b04-b4b9-80b5e97dce66), log id: 39e4af3f
>2014-07-01 11:28:40,713 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [63518f31] -- executeIrsBrokerCommand: calling 'setVolumeDescription' 
>2014-07-01 11:28:40,713 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [63518f31] -- setVolumeDescription parameters:
>                spUUID=af2c9167-5d67-4494-ae09-def33cf383c2
>                sdUUID=f0c702c0-aec8-49c2-a48c-0bf9e1fd447d
>                imageGroupGUID=1045141f-4321-4573-a40e-d9d455b2c624
>                volUUID=1fda29a1-0cf5-4b04-b4b9-80b5e97dce66
>                description={"Updated":true,"Disk Description":"OVF_STORE","Storage Domains":[{"uuid":"f0c702c0-aec8-49c2-a48c-0bf9e1fd447d"}],"Last Updated":"Tue Jul 01 11:28:35 IDT 2014","Size":225280}
>
>2014-07-01 11:28:40,864 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [63518f31] FINISH, SetVolumeDescriptionVDSCommand, log id: 39e4af3f
>2014-07-01 11:28:40,901 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-26) [63518f31] BaseAsyncTask::startPollingTask: Starting to poll task 381dbe8c-75d1-45ba-8a0b-72647163139a.
>2014-07-01 11:28:40,902 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-26) [63518f31] BaseAsyncTask::startPollingTask: Starting to poll task ea520d28-f522-46e6-90c6-92281522beb1.
>2014-07-01 11:28:40,932 INFO  [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (DefaultQuartzScheduler_Worker-26) [857c18e] Lock Acquired to object EngineLock [exclusiveLocks= key: 7c8dd991-f113-4b0e-8c56-3600ff5e6a06 value: DISK
>key: aa5195d9-e0fc-4108-86c9-260bf0e519fd value: DISK
>key: 57f19479-c31a-4885-8529-1d0f1120a024 value: STORAGE
>, sharedLocks= key: af2c9167-5d67-4494-ae09-def33cf383c2 value: OVF_UPDATE
>]
>2014-07-01 11:28:40,936 INFO  [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (DefaultQuartzScheduler_Worker-26) [857c18e] Running command: ProcessOvfUpdateForStorageDomainCommand internal: true. Entities affected :  ID: 57f19479-c31a-4885-8529-1d0f1120a024 Type: Storage
>2014-07-01 11:28:40,957 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [857c18e] START, SetVolumeDescriptionVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, storageDomainId = 57f19479-c31a-4885-8529-1d0f1120a024, imageGroupId = aa5195d9-e0fc-4108-86c9-260bf0e519fd, imageId = 483d0faa-60a7-4a7a-a266-bd981b662af1), log id: 6a2fb65f
>2014-07-01 11:28:41,166 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [857c18e] -- executeIrsBrokerCommand: calling 'setVolumeDescription' 
>2014-07-01 11:28:41,167 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [857c18e] -- setVolumeDescription parameters:
>                spUUID=af2c9167-5d67-4494-ae09-def33cf383c2
>                sdUUID=57f19479-c31a-4885-8529-1d0f1120a024
>                imageGroupGUID=aa5195d9-e0fc-4108-86c9-260bf0e519fd
>                volUUID=483d0faa-60a7-4a7a-a266-bd981b662af1
>                description={"Updated":false,"Disk Description":"OVF_STORE","Storage Domains":[{"uuid":"57f19479-c31a-4885-8529-1d0f1120a024"}],"Last Updated":"Tue Jul 01 11:22:08 IDT 2014"}
>
>2014-07-01 11:28:41,761 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [857c18e] FINISH, SetVolumeDescriptionVDSCommand, log id: 6a2fb65f
>2014-07-01 11:28:41,817 INFO  [org.ovirt.engine.core.bll.UploadStreamCommand] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] Running command: UploadStreamCommand internal: true. Entities affected :  ID: 57f19479-c31a-4885-8529-1d0f1120a024 Type: Storage
>2014-07-01 11:28:41,819 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] START, UploadStreamVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3), log id: 35fd90ab
>2014-07-01 11:28:41,819 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] -- executeVdsBrokerCommand: 
>2014-07-01 11:28:41,820 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] -- parameters:
>                spUUID=af2c9167-5d67-4494-ae09-def33cf383c2
>                sdUUID=57f19479-c31a-4885-8529-1d0f1120a024
>                imageGUID=aa5195d9-e0fc-4108-86c9-260bf0e519fd
>                volUUID=483d0faa-60a7-4a7a-a266-bd981b662af1
>                size=10,240
>
>2014-07-01 11:28:42,550 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] FINISH, UploadStreamVDSCommand, log id: 35fd90ab
>2014-07-01 11:28:42,582 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 33561185-22b7-4a4f-b55b-afc1c5edb84c
>2014-07-01 11:28:42,582 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] CommandMultiAsyncTasks::AttachTask: Attaching task 26c02c6e-aadf-4af7-ad71-bc14e295faf6 to command 33561185-22b7-4a4f-b55b-afc1c5edb84c.
>2014-07-01 11:28:42,598 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] Adding task 26c02c6e-aadf-4af7-ad71-bc14e295faf6 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2014-07-01 11:28:42,598 INFO  [org.ovirt.engine.core.bll.UploadStreamCommand] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] Lock freed to object EngineLock [exclusiveLocks= key: 7c8dd991-f113-4b0e-8c56-3600ff5e6a06 value: DISK
>key: aa5195d9-e0fc-4108-86c9-260bf0e519fd value: DISK
>key: 57f19479-c31a-4885-8529-1d0f1120a024 value: STORAGE
>, sharedLocks= key: af2c9167-5d67-4494-ae09-def33cf383c2 value: OVF_UPDATE
>]
>2014-07-01 11:28:42,604 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] START, SetVolumeDescriptionVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, storageDomainId = 57f19479-c31a-4885-8529-1d0f1120a024, imageGroupId = aa5195d9-e0fc-4108-86c9-260bf0e519fd, imageId = 483d0faa-60a7-4a7a-a266-bd981b662af1), log id: 67d7cba5
>2014-07-01 11:28:42,605 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] -- executeIrsBrokerCommand: calling 'setVolumeDescription' 
>2014-07-01 11:28:42,606 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] -- setVolumeDescription parameters:
>                spUUID=af2c9167-5d67-4494-ae09-def33cf383c2
>                sdUUID=57f19479-c31a-4885-8529-1d0f1120a024
>                imageGroupGUID=aa5195d9-e0fc-4108-86c9-260bf0e519fd
>                volUUID=483d0faa-60a7-4a7a-a266-bd981b662af1
>                description={"Updated":true,"Disk Description":"OVF_STORE","Storage Domains":[{"uuid":"57f19479-c31a-4885-8529-1d0f1120a024"}],"Last Updated":"Tue Jul 01 11:28:40 IDT 2014","Size":10240}
>
>2014-07-01 11:28:42,777 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] FINISH, SetVolumeDescriptionVDSCommand, log id: 67d7cba5
>2014-07-01 11:28:42,793 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] START, SetVolumeDescriptionVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, storageDomainId = 57f19479-c31a-4885-8529-1d0f1120a024, imageGroupId = 7c8dd991-f113-4b0e-8c56-3600ff5e6a06, imageId = 4cb53f95-29a9-41ac-9953-4a1c1827d1dd), log id: 343cce5d
>2014-07-01 11:28:42,796 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] -- executeIrsBrokerCommand: calling 'setVolumeDescription' 
>2014-07-01 11:28:42,797 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] -- setVolumeDescription parameters:
>                spUUID=af2c9167-5d67-4494-ae09-def33cf383c2
>                sdUUID=57f19479-c31a-4885-8529-1d0f1120a024
>                imageGroupGUID=7c8dd991-f113-4b0e-8c56-3600ff5e6a06
>                volUUID=4cb53f95-29a9-41ac-9953-4a1c1827d1dd
>                description={"Updated":false,"Disk Description":"OVF_STORE","Storage Domains":[{"uuid":"57f19479-c31a-4885-8529-1d0f1120a024"}],"Last Updated":"Tue Jul 01 11:22:08 IDT 2014"}
>
>2014-07-01 11:28:42,957 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [5d76fa6b] FINISH, SetVolumeDescriptionVDSCommand, log id: 343cce5d
>2014-07-01 11:28:42,985 INFO  [org.ovirt.engine.core.bll.UploadStreamCommand] (DefaultQuartzScheduler_Worker-26) [19902eb7] Running command: UploadStreamCommand internal: true. Entities affected :  ID: 57f19479-c31a-4885-8529-1d0f1120a024 Type: Storage
>2014-07-01 11:28:42,987 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-26) [19902eb7] START, UploadStreamVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3), log id: 6c1bba29
>2014-07-01 11:28:42,988 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-26) [19902eb7] -- executeVdsBrokerCommand: 
>2014-07-01 11:28:42,989 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-26) [19902eb7] -- parameters:
>                spUUID=af2c9167-5d67-4494-ae09-def33cf383c2
>                sdUUID=57f19479-c31a-4885-8529-1d0f1120a024
>                imageGUID=7c8dd991-f113-4b0e-8c56-3600ff5e6a06
>                volUUID=4cb53f95-29a9-41ac-9953-4a1c1827d1dd
>                size=10,240
>
>2014-07-01 11:28:44,106 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (DefaultQuartzScheduler_Worker-26) [19902eb7] FINISH, UploadStreamVDSCommand, log id: 6c1bba29
>2014-07-01 11:28:44,113 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-26) [19902eb7] CommandMultiAsyncTasks::AttachTask: Attaching task 8fce90a8-2685-4124-b7d4-fed419109aa9 to command 33561185-22b7-4a4f-b55b-afc1c5edb84c.
>2014-07-01 11:28:44,156 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-26) [19902eb7] Adding task 8fce90a8-2685-4124-b7d4-fed419109aa9 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2014-07-01 11:28:44,158 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler_Worker-26) [19902eb7] Trying to release a shared lock for key: af2c9167-5d67-4494-ae09-def33cf383c2OVF_UPDATE , but lock does not exist
>2014-07-01 11:28:44,158 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler_Worker-26) [19902eb7] Trying to release exclusive lock which does not exist, lock key: 7c8dd991-f113-4b0e-8c56-3600ff5e6a06DISK
>2014-07-01 11:28:44,159 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler_Worker-26) [19902eb7] Trying to release exclusive lock which does not exist, lock key: aa5195d9-e0fc-4108-86c9-260bf0e519fdDISK
>2014-07-01 11:28:44,160 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler_Worker-26) [19902eb7] Trying to release exclusive lock which does not exist, lock key: 57f19479-c31a-4885-8529-1d0f1120a024STORAGE
>2014-07-01 11:28:44,161 INFO  [org.ovirt.engine.core.bll.UploadStreamCommand] (DefaultQuartzScheduler_Worker-26) [19902eb7] Lock freed to object EngineLock [exclusiveLocks= key: 7c8dd991-f113-4b0e-8c56-3600ff5e6a06 value: DISK
>key: aa5195d9-e0fc-4108-86c9-260bf0e519fd value: DISK
>key: 57f19479-c31a-4885-8529-1d0f1120a024 value: STORAGE
>, sharedLocks= key: af2c9167-5d67-4494-ae09-def33cf383c2 value: OVF_UPDATE
>]
>2014-07-01 11:28:44,163 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [19902eb7] START, SetVolumeDescriptionVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, storageDomainId = 57f19479-c31a-4885-8529-1d0f1120a024, imageGroupId = 7c8dd991-f113-4b0e-8c56-3600ff5e6a06, imageId = 4cb53f95-29a9-41ac-9953-4a1c1827d1dd), log id: 40114cc3
>2014-07-01 11:28:44,164 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [19902eb7] -- executeIrsBrokerCommand: calling 'setVolumeDescription' 
>2014-07-01 11:28:44,165 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [19902eb7] -- setVolumeDescription parameters:
>                spUUID=af2c9167-5d67-4494-ae09-def33cf383c2
>                sdUUID=57f19479-c31a-4885-8529-1d0f1120a024
>                imageGroupGUID=7c8dd991-f113-4b0e-8c56-3600ff5e6a06
>                volUUID=4cb53f95-29a9-41ac-9953-4a1c1827d1dd
>                description={"Updated":true,"Disk Description":"OVF_STORE","Storage Domains":[{"uuid":"57f19479-c31a-4885-8529-1d0f1120a024"}],"Last Updated":"Tue Jul 01 11:28:40 IDT 2014","Size":10240}
>
>2014-07-01 11:28:44,334 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (DefaultQuartzScheduler_Worker-26) [19902eb7] FINISH, SetVolumeDescriptionVDSCommand, log id: 40114cc3
>2014-07-01 11:28:44,381 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-26) [19902eb7] BaseAsyncTask::startPollingTask: Starting to poll task 26c02c6e-aadf-4af7-ad71-bc14e295faf6.
>2014-07-01 11:28:44,382 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-26) [19902eb7] BaseAsyncTask::startPollingTask: Starting to poll task 8fce90a8-2685-4124-b7d4-fed419109aa9.
>2014-07-01 11:28:46,702 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-73) Polling and updating Async Tasks: 6 tasks, 4 tasks to poll now
>2014-07-01 11:28:46,713 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-73) SPMAsyncTask::PollTask: Polling task 26c02c6e-aadf-4af7-ad71-bc14e295faf6 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2014-07-01 11:28:46,714 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-73) BaseAsyncTask::OnTaskEndSuccess: Task 26c02c6e-aadf-4af7-ad71-bc14e295faf6 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2014-07-01 11:28:46,715 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-73) Task with DB Task ID 87a55e39-3471-4c12-95e0-8adb0f46ea81 and VDSM Task ID 8fce90a8-2685-4124-b7d4-fed419109aa9 is in state Polling. End action for command 33561185-22b7-4a4f-b55b-afc1c5edb84c will proceed when all the entitys tasks are completed.
>2014-07-01 11:28:46,716 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-73) SPMAsyncTask::PollTask: Polling task 381dbe8c-75d1-45ba-8a0b-72647163139a (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2014-07-01 11:28:46,717 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-73) BaseAsyncTask::OnTaskEndSuccess: Task 381dbe8c-75d1-45ba-8a0b-72647163139a (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2014-07-01 11:28:46,718 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-73) Task with DB Task ID 516539ee-725d-445c-9db5-e59232edf0b6 and VDSM Task ID ea520d28-f522-46e6-90c6-92281522beb1 is in state Polling. End action for command 63996fbc-7b84-4d8b-9cc1-da3b7ced955a will proceed when all the entitys tasks are completed.
>2014-07-01 11:28:46,720 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-73) SPMAsyncTask::PollTask: Polling task ea520d28-f522-46e6-90c6-92281522beb1 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2014-07-01 11:28:46,721 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-73) BaseAsyncTask::OnTaskEndSuccess: Task ea520d28-f522-46e6-90c6-92281522beb1 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2014-07-01 11:28:46,722 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-73) CommandAsyncTask::EndActionIfNecessary: All tasks of command 63996fbc-7b84-4d8b-9cc1-da3b7ced955a has ended -> executing endAction
>2014-07-01 11:28:46,723 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-73) CommandAsyncTask::endAction: Ending action for 2 tasks (command ID: 63996fbc-7b84-4d8b-9cc1-da3b7ced955a): calling endAction .
>2014-07-01 11:28:46,724 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-73) SPMAsyncTask::PollTask: Polling task 8fce90a8-2685-4124-b7d4-fed419109aa9 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2014-07-01 11:28:46,724 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-44) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction ProcessOvfUpdateForStorageDomain, executionIndex: 0
>2014-07-01 11:28:46,725 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-73) BaseAsyncTask::OnTaskEndSuccess: Task 8fce90a8-2685-4124-b7d4-fed419109aa9 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2014-07-01 11:28:46,726 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-73) CommandAsyncTask::EndActionIfNecessary: All tasks of command 33561185-22b7-4a4f-b55b-afc1c5edb84c has ended -> executing endAction
>2014-07-01 11:28:46,728 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-73) CommandAsyncTask::endAction: Ending action for 2 tasks (command ID: 33561185-22b7-4a4f-b55b-afc1c5edb84c): calling endAction .
>2014-07-01 11:28:46,729 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-35) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction ProcessOvfUpdateForStorageDomain, executionIndex: 0
>2014-07-01 11:28:46,755 INFO  [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (org.ovirt.thread.pool-8-thread-44) [6f766817] Ending command successfully: org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand
>2014-07-01 11:28:46,757 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-8-thread-44) [6f766817] Trying to release a shared lock for key: af2c9167-5d67-4494-ae09-def33cf383c2OVF_UPDATE , but lock does not exist
>2014-07-01 11:28:46,757 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-8-thread-44) [6f766817] Trying to release exclusive lock which does not exist, lock key: f0c702c0-aec8-49c2-a48c-0bf9e1fd447dSTORAGE
>2014-07-01 11:28:46,759 INFO  [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (org.ovirt.thread.pool-8-thread-35) [857c18e] Ending command successfully: org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand
>2014-07-01 11:28:46,758 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-8-thread-44) [6f766817] Trying to release exclusive lock which does not exist, lock key: 9a55a473-c481-4526-a26c-bf793c644fefDISK
>2014-07-01 11:28:46,760 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-8-thread-44) [6f766817] Trying to release exclusive lock which does not exist, lock key: 1045141f-4321-4573-a40e-d9d455b2c624DISK
>2014-07-01 11:28:46,761 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-8-thread-35) [857c18e] Trying to release a shared lock for key: af2c9167-5d67-4494-ae09-def33cf383c2OVF_UPDATE , but lock does not exist
>2014-07-01 11:28:46,763 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-8-thread-35) [857c18e] Trying to release exclusive lock which does not exist, lock key: 7c8dd991-f113-4b0e-8c56-3600ff5e6a06DISK
>2014-07-01 11:28:46,764 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-8-thread-35) [857c18e] Trying to release exclusive lock which does not exist, lock key: aa5195d9-e0fc-4108-86c9-260bf0e519fdDISK
>2014-07-01 11:28:46,765 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-8-thread-35) [857c18e] Trying to release exclusive lock which does not exist, lock key: 57f19479-c31a-4885-8529-1d0f1120a024STORAGE
>2014-07-01 11:28:46,766 INFO  [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (org.ovirt.thread.pool-8-thread-35) [857c18e] Lock freed to object EngineLock [exclusiveLocks= key: 7c8dd991-f113-4b0e-8c56-3600ff5e6a06 value: DISK
>key: aa5195d9-e0fc-4108-86c9-260bf0e519fd value: DISK
>key: 57f19479-c31a-4885-8529-1d0f1120a024 value: STORAGE
>, sharedLocks= key: af2c9167-5d67-4494-ae09-def33cf383c2 value: OVF_UPDATE
>]
>2014-07-01 11:28:46,768 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-35) [857c18e] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ProcessOvfUpdateForStorageDomain completed, handling the result.
>2014-07-01 11:28:46,768 INFO  [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (org.ovirt.thread.pool-8-thread-44) [6f766817] Lock freed to object EngineLock [exclusiveLocks= key: f0c702c0-aec8-49c2-a48c-0bf9e1fd447d value: STORAGE
>key: 9a55a473-c481-4526-a26c-bf793c644fef value: DISK
>key: 1045141f-4321-4573-a40e-d9d455b2c624 value: DISK
>, sharedLocks= key: af2c9167-5d67-4494-ae09-def33cf383c2 value: OVF_UPDATE
>]
>2014-07-01 11:28:46,769 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-35) [857c18e] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ProcessOvfUpdateForStorageDomain succeeded, clearing tasks.
>2014-07-01 11:28:46,770 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-44) [6f766817] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ProcessOvfUpdateForStorageDomain completed, handling the result.
>2014-07-01 11:28:46,770 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-35) [857c18e] SPMAsyncTask::ClearAsyncTask: Attempting to clear task 26c02c6e-aadf-4af7-ad71-bc14e295faf6
>2014-07-01 11:28:46,771 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-44) [6f766817] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ProcessOvfUpdateForStorageDomain succeeded, clearing tasks.
>2014-07-01 11:28:46,772 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-35) [857c18e] START, SPMClearTaskVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, taskId = 26c02c6e-aadf-4af7-ad71-bc14e295faf6), log id: 65a18380
>2014-07-01 11:28:46,773 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-44) [6f766817] SPMAsyncTask::ClearAsyncTask: Attempting to clear task 381dbe8c-75d1-45ba-8a0b-72647163139a
>2014-07-01 11:28:46,774 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-44) [6f766817] START, SPMClearTaskVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, taskId = 381dbe8c-75d1-45ba-8a0b-72647163139a), log id: 3f3614c4
>2014-07-01 11:28:46,776 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-35) [857c18e] START, HSMClearTaskVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, taskId=26c02c6e-aadf-4af7-ad71-bc14e295faf6), log id: 4ec07ca0
>2014-07-01 11:28:46,851 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-35) [857c18e] FINISH, HSMClearTaskVDSCommand, log id: 4ec07ca0
>2014-07-01 11:28:46,851 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-35) [857c18e] FINISH, SPMClearTaskVDSCommand, log id: 65a18380
>2014-07-01 11:28:46,853 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-44) [6f766817] START, HSMClearTaskVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, taskId=381dbe8c-75d1-45ba-8a0b-72647163139a), log id: 1b6a820c
>2014-07-01 11:28:46,873 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-35) [857c18e] BaseAsyncTask::RemoveTaskFromDB: Removed task 26c02c6e-aadf-4af7-ad71-bc14e295faf6 from DataBase
>2014-07-01 11:28:46,874 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-35) [857c18e] SPMAsyncTask::ClearAsyncTask: Attempting to clear task 8fce90a8-2685-4124-b7d4-fed419109aa9
>2014-07-01 11:28:46,874 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-35) [857c18e] START, SPMClearTaskVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, taskId = 8fce90a8-2685-4124-b7d4-fed419109aa9), log id: 4888199d
>2014-07-01 11:28:46,956 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-44) [6f766817] FINISH, HSMClearTaskVDSCommand, log id: 1b6a820c
>2014-07-01 11:28:46,957 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-44) [6f766817] FINISH, SPMClearTaskVDSCommand, log id: 3f3614c4
>2014-07-01 11:28:46,959 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-35) [857c18e] START, HSMClearTaskVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, taskId=8fce90a8-2685-4124-b7d4-fed419109aa9), log id: 5f49b79e
>2014-07-01 11:28:46,973 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-44) [6f766817] BaseAsyncTask::RemoveTaskFromDB: Removed task 381dbe8c-75d1-45ba-8a0b-72647163139a from DataBase
>2014-07-01 11:28:46,974 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-44) [6f766817] SPMAsyncTask::ClearAsyncTask: Attempting to clear task ea520d28-f522-46e6-90c6-92281522beb1
>2014-07-01 11:28:46,974 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-44) [6f766817] START, SPMClearTaskVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, taskId = ea520d28-f522-46e6-90c6-92281522beb1), log id: 3acddbd5
>2014-07-01 11:28:47,056 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-35) [857c18e] FINISH, HSMClearTaskVDSCommand, log id: 5f49b79e
>2014-07-01 11:28:47,057 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-35) [857c18e] FINISH, SPMClearTaskVDSCommand, log id: 4888199d
>2014-07-01 11:28:47,059 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-44) [6f766817] START, HSMClearTaskVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, taskId=ea520d28-f522-46e6-90c6-92281522beb1), log id: 211b9b1c
>2014-07-01 11:28:47,073 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-35) [857c18e] BaseAsyncTask::RemoveTaskFromDB: Removed task 8fce90a8-2685-4124-b7d4-fed419109aa9 from DataBase
>2014-07-01 11:28:47,074 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-35) [857c18e] CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 33561185-22b7-4a4f-b55b-afc1c5edb84c
>2014-07-01 11:28:47,161 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-44) [6f766817] FINISH, HSMClearTaskVDSCommand, log id: 211b9b1c
>2014-07-01 11:28:47,162 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-44) [6f766817] FINISH, SPMClearTaskVDSCommand, log id: 3acddbd5
>2014-07-01 11:28:47,173 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-44) [6f766817] BaseAsyncTask::RemoveTaskFromDB: Removed task ea520d28-f522-46e6-90c6-92281522beb1 from DataBase
>2014-07-01 11:28:47,174 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-44) [6f766817] CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 63996fbc-7b84-4d8b-9cc1-da3b7ced955a
>2014-07-01 11:28:51,773 INFO  [org.ovirt.engine.core.bll.UpdateVdsGroupCommand] (http--0.0.0.0-8080-2) [1f738c8c] Running command: UpdateVdsGroupCommand internal: false. Entities affected :  ID: b2e953df-84eb-4383-bdd3-374d9ac4de97 Type: VdsGroups
>2014-07-01 11:28:51,807 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-2) [1f738c8c] Correlation ID: 1f738c8c, Call Stack: null, Custom Event ID: -1, Message: Host cluster cluster33 was updated by admin
>2014-07-01 11:28:59,296 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-2) Setting new tasks map. The map contains now 4 tasks
>2014-07-01 11:29:11,296 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (http--0.0.0.0-8080-4) [73b4617e] Lock Acquired to object EngineLock [exclusiveLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM
>, sharedLocks= ]
>2014-07-01 11:29:11,425 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http--0.0.0.0-8080-4) [73b4617e] START, IsVmDuringInitiatingVDSCommand( vmId = 2dfeb494-de75-4b7a-9295-76dd569fbe11), log id: 468430fd
>2014-07-01 11:29:11,430 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http--0.0.0.0-8080-4) [73b4617e] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 468430fd
>2014-07-01 11:29:11,456 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (http--0.0.0.0-8080-4) [73b4617e] Candidate host venus-vdsa (95070901-19ea-4f27-9852-bb106cb88cb3) was filtered out by VAR__FILTERTYPE__INTERNAL filter Network
>2014-07-01 11:29:11,458 WARN  [org.ovirt.engine.core.bll.RunVmCommand] (http--0.0.0.0-8080-4) [73b4617e] CanDoAction of action RunVm failed. Reasons:VAR__ACTION__RUN,VAR__TYPE__VM,SCHEDULING_ALL_HOSTS_FILTERED_OUT,VAR__FILTERTYPE__INTERNAL,$hostName venus-vdsa,$filterName Network,$networkNames ,VAR__DETAIL__NETWORK_MISSING,SCHEDULING_HOST_FILTERED_REASON_WITH_DETAIL
>2014-07-01 11:29:11,459 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (http--0.0.0.0-8080-4) [73b4617e] Lock freed to object EngineLock [exclusiveLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM
>, sharedLocks= ]
>2014-07-01 11:29:26,965 INFO  [org.ovirt.engine.core.bll.network.vm.AddVmInterfaceCommand] (http--0.0.0.0-8080-2) [318dde] Running command: AddVmInterfaceCommand internal: false. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM,  ID: 5e6b2e06-1e0a-4ab0-9941-080c83adebed Type: VnicProfile
>2014-07-01 11:29:27,133 INFO  [org.ovirt.engine.core.bll.network.vm.ActivateDeactivateVmNicCommand] (http--0.0.0.0-8080-2) [77492f1c] Running command: ActivateDeactivateVmNicCommand internal: true. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM
>2014-07-01 11:29:27,170 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-2) [77492f1c] Correlation ID: 77492f1c, Call Stack: null, Custom Event ID: -1, Message: Network Interface nic1 (VirtIO) was plugged to VM rnoriVM. (User: admin)
>2014-07-01 11:29:27,195 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-2) [77492f1c] Correlation ID: 318dde, Call Stack: null, Custom Event ID: -1, Message: Interface nic1 (VirtIO) was added to VM rnoriVM. (User: admin)
>2014-07-01 11:29:32,364 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (http--0.0.0.0-8080-2) [71cf340b] Lock Acquired to object EngineLock [exclusiveLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM
>, sharedLocks= ]
>2014-07-01 11:29:32,442 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http--0.0.0.0-8080-2) [71cf340b] START, IsVmDuringInitiatingVDSCommand( vmId = 2dfeb494-de75-4b7a-9295-76dd569fbe11), log id: 47679d74
>2014-07-01 11:29:32,443 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http--0.0.0.0-8080-2) [71cf340b] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 47679d74
>2014-07-01 11:29:32,501 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (http--0.0.0.0-8080-2) [71cf340b] Candidate host venus-vdsa (95070901-19ea-4f27-9852-bb106cb88cb3) was filtered out by VAR__FILTERTYPE__INTERNAL filter Network
>2014-07-01 11:29:32,502 WARN  [org.ovirt.engine.core.bll.RunVmCommand] (http--0.0.0.0-8080-2) [71cf340b] CanDoAction of action RunVm failed. Reasons:VAR__ACTION__RUN,VAR__TYPE__VM,SCHEDULING_ALL_HOSTS_FILTERED_OUT,VAR__FILTERTYPE__INTERNAL,$hostName venus-vdsa,$filterName Network,$networkNames ,VAR__DETAIL__NETWORK_MISSING,SCHEDULING_HOST_FILTERED_REASON_WITH_DETAIL
>2014-07-01 11:29:32,503 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (http--0.0.0.0-8080-2) [71cf340b] Lock freed to object EngineLock [exclusiveLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM
>, sharedLocks= ]
>2014-07-01 11:29:44,395 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-90) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:29:59,298 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-26) [19902eb7] Setting new tasks map. The map contains now 0 tasks
>2014-07-01 11:29:59,299 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-26) [19902eb7] Cleared all tasks of pool af2c9167-5d67-4494-ae09-def33cf383c2.
>2014-07-01 11:30:44,434 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-25) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:31:24,295 INFO  [org.ovirt.engine.core.bll.network.dc.RemoveNetworkCommand] (org.ovirt.thread.pool-8-thread-17) [f4e1898] Running command: RemoveNetworkCommand internal: false. Entities affected :  ID: f9b9cc55-1280-49a1-806f-91f440a52872 Type: Network
>2014-07-01 11:31:24,441 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-17) [f4e1898] Correlation ID: f4e1898, Call Stack: null, Custom Event ID: -1, Message: Network New_Netowork was removed from Data Center: DC35_Maor
>2014-07-01 11:31:44,479 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-69) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:31:47,693 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (http--0.0.0.0-8080-2) [3d5ea2ff] Lock Acquired to object EngineLock [exclusiveLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM
>, sharedLocks= ]
>2014-07-01 11:31:47,759 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http--0.0.0.0-8080-2) [3d5ea2ff] START, IsVmDuringInitiatingVDSCommand( vmId = 2dfeb494-de75-4b7a-9295-76dd569fbe11), log id: 25ce181b
>2014-07-01 11:31:47,760 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http--0.0.0.0-8080-2) [3d5ea2ff] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 25ce181b
>2014-07-01 11:31:47,828 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-48) [3d5ea2ff] Running command: RunVmCommand internal: false. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM
>2014-07-01 11:31:48,033 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-48) [3d5ea2ff] START, UpdateVmDynamicDataVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmDynamic=org.ovirt.engine.core.common.businessentities.VmDynamic@5b41fdba), log id: 4a311600
>2014-07-01 11:31:48,045 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-48) [3d5ea2ff] FINISH, UpdateVmDynamicDataVDSCommand, log id: 4a311600
>2014-07-01 11:31:48,052 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-8-thread-48) [3d5ea2ff] START, CreateVmVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, vm=VM [rnoriVM]), log id: 2c5673e2
>2014-07-01 11:31:48,072 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-48) [3d5ea2ff] START, CreateVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, vm=VM [rnoriVM]), log id: 543b5171
>2014-07-01 11:31:48,168 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-48) [3d5ea2ff] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand memSize=1024,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=rhel6.5.0,keyboardLayout=en-us,memGuaranteedSize=1024,nice=0,display=qxl,smartcardEnable=false,bootMenuEnable=false,numaTune={mode=preferred},smpCoresPerSocket=1,maxVCpus=160,copyPasteEnable=true,timeOffset=0,transparentHugePages=true,vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11,devices=[{specParams={vram=32768, heads=1}, device=qxl, type=video, deviceId=b5f5f2cd-535b-4b77-940d-b508f035ae76}, {shared=false, iface=ide, index=2, specParams={path=}, path=, device=cdrom, type=disk, readonly=true, deviceId=1aa45b98-a35b-4d7e-a234-3c3d83e16f4f}, {shared=false, index=0, volumeID=e414e87f-b6e6-41d4-a5a5-df5c242e6185, propagateErrors=off, format=raw, type=disk, iface=virtio, bootOrder=1, domainID=57f19479-c31a-4885-8529-1d0f1120a024, imageID=3c320707-752e-4364-9fc5-b489e0cccf97, specParams={}, optional=false, device=disk, poolID=af2c9167-5d67-4494-ae09-def33cf383c2, readonly=false, deviceId=3c320707-752e-4364-9fc5-b489e0cccf97}, {shared=false, volumeID=9792688f-a570-4ff4-98ca-911b6921b3e0, iface=virtio, imageID=60b052a9-8ca8-4855-ad23-efa9e79aab48, domainID=f0c702c0-aec8-49c2-a48c-0bf9e1fd447d, specParams={}, optional=false, propagateErrors=off, device=disk, poolID=af2c9167-5d67-4494-ae09-def33cf383c2, format=raw, type=disk, readonly=false, deviceId=60b052a9-8ca8-4855-ad23-efa9e79aab48}, {nicModel=pv, specParams={outbound={}, inbound={}}, macAddr=00:1a:4a:16:01:51, device=bridge, linkActive=true, type=interface, filter=vdsm-no-mac-spoofing, network=ovirtmgmt, deviceId=1fad0f83-a3aa-4c18-9daa-556a3bffd800}, {index=0, model=virtio-scsi, specParams={}, device=scsi, type=controller, deviceId=d6830aae-2bca-438f-9d39-d8c79651fc29}],acpiEnable=true,vmName=rnoriVM,fileTransferEnable=true,cpuType=Conroe,custom={}
>2014-07-01 11:31:48,231 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-48) [3d5ea2ff] FINISH, CreateVDSCommand, log id: 543b5171
>2014-07-01 11:31:48,262 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-8-thread-48) [3d5ea2ff] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 2c5673e2
>2014-07-01 11:31:48,263 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-48) [3d5ea2ff] Lock freed to object EngineLock [exclusiveLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM
>, sharedLocks= ]
>2014-07-01 11:31:48,295 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-48) [3d5ea2ff] Correlation ID: 3d5ea2ff, Job ID: 68398181-902c-4ff2-a11c-5df8aa09cef8, Call Stack: null, Custom Event ID: -1, Message: VM rnoriVM was started by admin (Host: venus-vdsa).
>2014-07-01 11:31:50,988 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-72) VM rnoriVM 2dfeb494-de75-4b7a-9295-76dd569fbe11 moved from WaitForLaunch --> PoweringUp
>2014-07-01 11:31:50,998 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-72) START, FullListVdsCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vds=Host[venus-vdsa,95070901-19ea-4f27-9852-bb106cb88cb3], vmIds=[2dfeb494-de75-4b7a-9295-76dd569fbe11]), log id: 226c234e
>2014-07-01 11:31:51,255 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-72) FINISH, FullListVdsCommand, return: [{displaySecurePort=-1, kvmEnable=true, nicModel=rtl8139,pv, keyboardLayout=en-us, displayIp=0, pauseCode=NOERR, nice=0, copyPasteEnable=true, timeOffset=0, transparentHugePages=true, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, acpiEnable=true, custom={}, memSize=1024, smp=1, displayPort=5900, emulatedMachine=rhel6.5.0, vmType=kvm, status=Up, memGuaranteedSize=1024, display=qxl, pid=26398, smartcardEnable=false, bootMenuEnable=false, numaTune={mode=preferred}, smpCoresPerSocket=1, maxVCpus=160, clientIp=, devices=[Ljava.lang.Object;@b5eb70a, vmName=rnoriVM, fileTransferEnable=true, cpuType=Conroe}], log id: 226c234e
>2014-07-01 11:31:51,260 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-72) Received a memballoon Device without an address when processing VM 2dfeb494-de75-4b7a-9295-76dd569fbe11 devices, skipping device: {alias=balloon0, specParams={model=none}, device=memballoon, type=balloon}
>2014-07-01 11:31:51,262 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-72) Received a spice Device without an address when processing VM 2dfeb494-de75-4b7a-9295-76dd569fbe11 devices, skipping device: {port=5900, specParams={keyMap=en-us, copyPasteEnable=true, displayIp=0}, device=spice, type=graphics}
>2014-07-01 11:32:44,538 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-7) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:32:50,385 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-46) VM rnoriVM 2dfeb494-de75-4b7a-9295-76dd569fbe11 moved from PoweringUp --> Up
>2014-07-01 11:32:50,429 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-46) Correlation ID: 3d5ea2ff, Job ID: 68398181-902c-4ff2-a11c-5df8aa09cef8, Call Stack: null, Custom Event ID: -1, Message: VM rnoriVM started on Host venus-vdsa
>2014-07-01 11:33:16,249 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-11) [5f5c554a] Running command: ShutdownVmCommand internal: false. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM
>2014-07-01 11:33:16,258 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-11) [5f5c554a] Entered (VM rnoriVM).
>2014-07-01 11:33:16,259 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-11) [5f5c554a] Sending shutdown command for VM rnoriVM.
>2014-07-01 11:33:16,264 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-11) [5f5c554a] START, DestroyVmVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, force=false, secondsToWait=30, gracefully=true, reason=Liron is Behema), log id: 792c7c60
>2014-07-01 11:33:16,272 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-11) [5f5c554a] START, DestroyVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, force=false, secondsToWait=30, gracefully=true, reason=Liron is Behema), log id: 231ead4e
>2014-07-01 11:33:16,320 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-11) [5f5c554a] FINISH, DestroyVDSCommand, log id: 231ead4e
>2014-07-01 11:33:16,333 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-11) [5f5c554a] FINISH, DestroyVmVDSCommand, return: PoweringDown, log id: 792c7c60
>2014-07-01 11:33:16,358 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-11) [5f5c554a] Correlation ID: 5f5c554a, Job ID: 655a32a1-e855-4b3f-a5f5-f2ba061eed84, Call Stack: null, Custom Event ID: -1, Message: VM shutdown initiated by admin on VM rnoriVM (Host: venus-vdsa) (Reason: Liron is Behema).
>2014-07-01 11:33:44,613 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-45) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:34:44,679 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-73) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:34:46,920 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-18) [5b44b31] Running command: ShutdownVmCommand internal: false. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM
>2014-07-01 11:34:46,924 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-18) [5b44b31] Entered (VM rnoriVM).
>2014-07-01 11:34:46,925 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-18) [5b44b31] Cannot shutdown VM rnoriVM, status is not up. Stopping instead.
>2014-07-01 11:34:47,020 INFO  [org.ovirt.engine.core.bll.StopVmCommand] (org.ovirt.thread.pool-8-thread-18) [6013f158] Running command: StopVmCommand internal: true. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM
>2014-07-01 11:34:47,027 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-18) [6013f158] START, DestroyVmVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, force=false, secondsToWait=0, gracefully=false, reason=), log id: 7b276899
>2014-07-01 11:34:47,090 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-18) [6013f158] START, DestroyVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, force=false, secondsToWait=0, gracefully=false, reason=), log id: 7cad70f5
>2014-07-01 11:34:47,632 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-18) [6013f158] FINISH, DestroyVDSCommand, log id: 7cad70f5
>2014-07-01 11:34:47,673 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-18) [6013f158] FINISH, DestroyVmVDSCommand, return: Down, log id: 7b276899
>2014-07-01 11:34:47,720 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-18) [6013f158] Correlation ID: 6013f158, Job ID: 0791c391-b58b-4aff-8502-c7688d81d8c4, Call Stack: null, Custom Event ID: -1, Message: VM rnoriVM was powered off ungracefully by admin (Host: venus-vdsa) (Reason: Not Specified).
>2014-07-01 11:34:47,737 INFO  [org.ovirt.engine.core.bll.ProcessDownVmCommand] (org.ovirt.thread.pool-8-thread-32) [3fc35414] Running command: ProcessDownVmCommand internal: true.
>2014-07-01 11:34:59,514 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (http--0.0.0.0-8080-2) [15da0592] Lock Acquired to object EngineLock [exclusiveLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM
>, sharedLocks= ]
>2014-07-01 11:34:59,610 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http--0.0.0.0-8080-2) [15da0592] START, IsVmDuringInitiatingVDSCommand( vmId = 2dfeb494-de75-4b7a-9295-76dd569fbe11), log id: 4672765
>2014-07-01 11:34:59,611 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http--0.0.0.0-8080-2) [15da0592] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 4672765
>2014-07-01 11:34:59,682 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-39) [15da0592] Running command: RunVmCommand internal: false. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM
>2014-07-01 11:34:59,771 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-39) [15da0592] START, UpdateVmDynamicDataVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmDynamic=org.ovirt.engine.core.common.businessentities.VmDynamic@ae870ed6), log id: 6fe19c2d
>2014-07-01 11:34:59,782 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-39) [15da0592] FINISH, UpdateVmDynamicDataVDSCommand, log id: 6fe19c2d
>2014-07-01 11:34:59,784 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-8-thread-39) [15da0592] START, CreateVmVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, vm=VM [rnoriVM]), log id: 2b212af5
>2014-07-01 11:34:59,790 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-39) [15da0592] START, CreateVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, vm=VM [rnoriVM]), log id: de9d993
>2014-07-01 11:34:59,818 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-39) [15da0592] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand memSize=1024,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=rhel6.5.0,keyboardLayout=en-us,memGuaranteedSize=1024,nice=0,display=qxl,smartcardEnable=false,bootMenuEnable=false,numaTune={mode=preferred},smpCoresPerSocket=1,maxVCpus=160,copyPasteEnable=true,timeOffset=0,transparentHugePages=true,vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11,devices=[{address={bus=0x00, domain=0x0000, slot=0x02, type=pci, function=0x0}, specParams={vram=32768, heads=1}, device=qxl, type=video, deviceId=b5f5f2cd-535b-4b77-940d-b508f035ae76}, {shared=false, iface=ide, index=2, address={unit=0, bus=1, target=0, controller=0, type=drive}, specParams={path=}, path=, device=cdrom, type=disk, readonly=true, deviceId=1aa45b98-a35b-4d7e-a234-3c3d83e16f4f}, {shared=false, index=0, volumeID=e414e87f-b6e6-41d4-a5a5-df5c242e6185, propagateErrors=off, format=raw, type=disk, iface=virtio, bootOrder=1, address={bus=0x00, domain=0x0000, slot=0x06, type=pci, function=0x0}, domainID=57f19479-c31a-4885-8529-1d0f1120a024, imageID=3c320707-752e-4364-9fc5-b489e0cccf97, specParams={}, optional=false, device=disk, poolID=af2c9167-5d67-4494-ae09-def33cf383c2, readonly=false, deviceId=3c320707-752e-4364-9fc5-b489e0cccf97}, {shared=false, volumeID=9792688f-a570-4ff4-98ca-911b6921b3e0, iface=virtio, imageID=60b052a9-8ca8-4855-ad23-efa9e79aab48, domainID=f0c702c0-aec8-49c2-a48c-0bf9e1fd447d, address={bus=0x00, domain=0x0000, slot=0x07, type=pci, function=0x0}, specParams={}, optional=false, propagateErrors=off, device=disk, poolID=af2c9167-5d67-4494-ae09-def33cf383c2, format=raw, type=disk, readonly=false, deviceId=60b052a9-8ca8-4855-ad23-efa9e79aab48}, {nicModel=pv, address={bus=0x00, domain=0x0000, slot=0x03, type=pci, function=0x0}, specParams={outbound={}, inbound={}}, macAddr=00:1a:4a:16:01:51, device=bridge, linkActive=true, type=interface, filter=vdsm-no-mac-spoofing, network=ovirtmgmt, deviceId=1fad0f83-a3aa-4c18-9daa-556a3bffd800}, {index=0, model=virtio-scsi, address={bus=0x00, domain=0x0000, slot=0x04, type=pci, function=0x0}, specParams={}, device=scsi, type=controller, deviceId=d6830aae-2bca-438f-9d39-d8c79651fc29}],acpiEnable=true,vmName=rnoriVM,fileTransferEnable=true,cpuType=Conroe,custom={device_c0c46599-e37d-4ffd-8c5a-986b23ae9452device_e7b46b5a-e55c-4546-951e-422758dc3bdadevice_08c16267-7dd4-4120-a5ff-2392b9dfb81cdevice_c31c802b-1e4a-4048-b5a7-6f27214496a7device_248b7f07-c876-4d9e-8eb7-aa31adbf9769=VmDevice {vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, deviceId=248b7f07-c876-4d9e-8eb7-aa31adbf9769, device=spicevmc, type=CHANNEL, bootOrder=0, specParams={}, address={port=3, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel2, customProperties={}, snapshotId=null}, device_c0c46599-e37d-4ffd-8c5a-986b23ae9452device_e7b46b5a-e55c-4546-951e-422758dc3bda=VmDevice {vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, deviceId=e7b46b5a-e55c-4546-951e-422758dc3bda, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x05, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}, snapshotId=null}, device_c0c46599-e37d-4ffd-8c5a-986b23ae9452device_e7b46b5a-e55c-4546-951e-422758dc3bdadevice_08c16267-7dd4-4120-a5ff-2392b9dfb81c=VmDevice {vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, deviceId=08c16267-7dd4-4120-a5ff-2392b9dfb81c, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=1, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel0, customProperties={}, snapshotId=null}, device_c0c46599-e37d-4ffd-8c5a-986b23ae9452=VmDevice {vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, deviceId=c0c46599-e37d-4ffd-8c5a-986b23ae9452, device=ide, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x1}, managed=false, plugged=true, readOnly=false, deviceAlias=ide0, customProperties={}, snapshotId=null}, device_c0c46599-e37d-4ffd-8c5a-986b23ae9452device_e7b46b5a-e55c-4546-951e-422758dc3bdadevice_08c16267-7dd4-4120-a5ff-2392b9dfb81cdevice_c31c802b-1e4a-4048-b5a7-6f27214496a7=VmDevice {vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, deviceId=c31c802b-1e4a-4048-b5a7-6f27214496a7, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=2, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel1, customProperties={}, snapshotId=null}}
>2014-07-01 11:34:59,877 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-39) [15da0592] FINISH, CreateVDSCommand, log id: de9d993
>2014-07-01 11:34:59,890 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-8-thread-39) [15da0592] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 2b212af5
>2014-07-01 11:34:59,891 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-39) [15da0592] Lock freed to object EngineLock [exclusiveLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM
>, sharedLocks= ]
>2014-07-01 11:34:59,908 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-39) [15da0592] Correlation ID: 15da0592, Job ID: 2429a0fc-160d-47d0-b7a6-e8ad974264c5, Call Stack: null, Custom Event ID: -1, Message: VM rnoriVM was started by admin (Host: venus-vdsa).
>2014-07-01 11:35:02,985 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-51) VM rnoriVM 2dfeb494-de75-4b7a-9295-76dd569fbe11 moved from WaitForLaunch --> PoweringUp
>2014-07-01 11:35:40,366 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-50) [219e73e7] Running command: ShutdownVmCommand internal: false. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM
>2014-07-01 11:35:40,369 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-50) [219e73e7] Entered (VM rnoriVM).
>2014-07-01 11:35:40,369 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-50) [219e73e7] Cannot shutdown VM rnoriVM, status is not up. Stopping instead.
>2014-07-01 11:35:40,433 INFO  [org.ovirt.engine.core.bll.StopVmCommand] (org.ovirt.thread.pool-8-thread-50) [1e8a94e7] Running command: StopVmCommand internal: true. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM
>2014-07-01 11:35:40,439 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-50) [1e8a94e7] START, DestroyVmVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, force=false, secondsToWait=0, gracefully=false, reason=), log id: 64c60e30
>2014-07-01 11:35:40,494 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-50) [1e8a94e7] START, DestroyVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, force=false, secondsToWait=0, gracefully=false, reason=), log id: 21cb7b75
>2014-07-01 11:35:40,994 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-50) [1e8a94e7] FINISH, DestroyVDSCommand, log id: 21cb7b75
>2014-07-01 11:35:41,041 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-50) [1e8a94e7] FINISH, DestroyVmVDSCommand, return: Down, log id: 64c60e30
>2014-07-01 11:35:41,053 INFO  [org.ovirt.engine.core.bll.ProcessDownVmCommand] (org.ovirt.thread.pool-8-thread-1) [252bca7] Running command: ProcessDownVmCommand internal: true.
>2014-07-01 11:35:41,058 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-50) [1e8a94e7] Correlation ID: 1e8a94e7, Job ID: b10d7d7c-3e8f-4877-9e49-83094557de34, Call Stack: null, Custom Event ID: -1, Message: VM rnoriVM was powered off ungracefully by admin (Host: venus-vdsa) (Reason: Not Specified).
>2014-07-01 11:35:44,710 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-17) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:36:44,765 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-50) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:37:10,789 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (http--0.0.0.0-8080-2) [669527a3] Lock Acquired to object EngineLock [exclusiveLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM
>, sharedLocks= ]
>2014-07-01 11:37:10,931 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http--0.0.0.0-8080-2) [669527a3] START, IsVmDuringInitiatingVDSCommand( vmId = 2dfeb494-de75-4b7a-9295-76dd569fbe11), log id: 202da319
>2014-07-01 11:37:10,931 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http--0.0.0.0-8080-2) [669527a3] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 202da319
>2014-07-01 11:37:11,003 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-2) [669527a3] Running command: RunVmCommand internal: false. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM
>2014-07-01 11:37:11,066 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-2) [669527a3] START, UpdateVmDynamicDataVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmDynamic=org.ovirt.engine.core.common.businessentities.VmDynamic@16f5bbc8), log id: 6b01c6f4
>2014-07-01 11:37:11,078 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-2) [669527a3] FINISH, UpdateVmDynamicDataVDSCommand, log id: 6b01c6f4
>2014-07-01 11:37:11,081 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-8-thread-2) [669527a3] START, CreateVmVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, vm=VM [rnoriVM]), log id: 6ffd05c2
>2014-07-01 11:37:11,088 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-2) [669527a3] START, CreateVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, vm=VM [rnoriVM]), log id: 5453c63
>2014-07-01 11:37:11,124 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-2) [669527a3] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand memSize=1024,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=rhel6.5.0,keyboardLayout=en-us,memGuaranteedSize=1024,nice=0,display=qxl,smartcardEnable=false,bootMenuEnable=false,numaTune={mode=preferred},smpCoresPerSocket=1,maxVCpus=160,copyPasteEnable=true,timeOffset=0,transparentHugePages=true,vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11,devices=[{address={bus=0x00, domain=0x0000, slot=0x02, type=pci, function=0x0}, specParams={vram=32768, heads=1}, device=qxl, type=video, deviceId=b5f5f2cd-535b-4b77-940d-b508f035ae76}, {shared=false, iface=ide, index=2, address={unit=0, bus=1, target=0, controller=0, type=drive}, specParams={path=}, path=, device=cdrom, type=disk, readonly=true, deviceId=1aa45b98-a35b-4d7e-a234-3c3d83e16f4f}, {shared=false, index=0, volumeID=e414e87f-b6e6-41d4-a5a5-df5c242e6185, propagateErrors=off, format=raw, type=disk, iface=virtio, bootOrder=1, address={bus=0x00, domain=0x0000, slot=0x06, type=pci, function=0x0}, domainID=57f19479-c31a-4885-8529-1d0f1120a024, imageID=3c320707-752e-4364-9fc5-b489e0cccf97, specParams={}, optional=false, device=disk, poolID=af2c9167-5d67-4494-ae09-def33cf383c2, readonly=false, deviceId=3c320707-752e-4364-9fc5-b489e0cccf97}, {shared=false, volumeID=9792688f-a570-4ff4-98ca-911b6921b3e0, iface=virtio, imageID=60b052a9-8ca8-4855-ad23-efa9e79aab48, domainID=f0c702c0-aec8-49c2-a48c-0bf9e1fd447d, address={bus=0x00, domain=0x0000, slot=0x07, type=pci, function=0x0}, specParams={}, optional=false, propagateErrors=off, device=disk, poolID=af2c9167-5d67-4494-ae09-def33cf383c2, format=raw, type=disk, readonly=false, deviceId=60b052a9-8ca8-4855-ad23-efa9e79aab48}, {nicModel=pv, address={bus=0x00, domain=0x0000, slot=0x03, type=pci, function=0x0}, specParams={outbound={}, inbound={}}, macAddr=00:1a:4a:16:01:51, device=bridge, linkActive=true, type=interface, filter=vdsm-no-mac-spoofing, network=ovirtmgmt, deviceId=1fad0f83-a3aa-4c18-9daa-556a3bffd800}, {index=0, model=virtio-scsi, address={bus=0x00, domain=0x0000, slot=0x04, type=pci, function=0x0}, specParams={}, device=scsi, type=controller, deviceId=d6830aae-2bca-438f-9d39-d8c79651fc29}],acpiEnable=true,vmName=rnoriVM,fileTransferEnable=true,cpuType=Conroe,custom={device_c0c46599-e37d-4ffd-8c5a-986b23ae9452device_e7b46b5a-e55c-4546-951e-422758dc3bdadevice_08c16267-7dd4-4120-a5ff-2392b9dfb81cdevice_c31c802b-1e4a-4048-b5a7-6f27214496a7device_248b7f07-c876-4d9e-8eb7-aa31adbf9769=VmDevice {vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, deviceId=248b7f07-c876-4d9e-8eb7-aa31adbf9769, device=spicevmc, type=CHANNEL, bootOrder=0, specParams={}, address={port=3, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel2, customProperties={}, snapshotId=null}, device_c0c46599-e37d-4ffd-8c5a-986b23ae9452device_e7b46b5a-e55c-4546-951e-422758dc3bda=VmDevice {vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, deviceId=e7b46b5a-e55c-4546-951e-422758dc3bda, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x05, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}, snapshotId=null}, device_c0c46599-e37d-4ffd-8c5a-986b23ae9452device_e7b46b5a-e55c-4546-951e-422758dc3bdadevice_08c16267-7dd4-4120-a5ff-2392b9dfb81c=VmDevice {vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, deviceId=08c16267-7dd4-4120-a5ff-2392b9dfb81c, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=1, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel0, customProperties={}, snapshotId=null}, device_c0c46599-e37d-4ffd-8c5a-986b23ae9452=VmDevice {vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, deviceId=c0c46599-e37d-4ffd-8c5a-986b23ae9452, device=ide, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x1}, managed=false, plugged=true, readOnly=false, deviceAlias=ide0, customProperties={}, snapshotId=null}, device_c0c46599-e37d-4ffd-8c5a-986b23ae9452device_e7b46b5a-e55c-4546-951e-422758dc3bdadevice_08c16267-7dd4-4120-a5ff-2392b9dfb81cdevice_c31c802b-1e4a-4048-b5a7-6f27214496a7=VmDevice {vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, deviceId=c31c802b-1e4a-4048-b5a7-6f27214496a7, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=2, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel1, customProperties={}, snapshotId=null}}
>2014-07-01 11:37:11,171 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-2) [669527a3] FINISH, CreateVDSCommand, log id: 5453c63
>2014-07-01 11:37:11,186 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-8-thread-2) [669527a3] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 6ffd05c2
>2014-07-01 11:37:11,187 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-2) [669527a3] Lock freed to object EngineLock [exclusiveLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM
>, sharedLocks= ]
>2014-07-01 11:37:11,203 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-2) [669527a3] Correlation ID: 669527a3, Job ID: 53e7a254-d565-450d-b1e3-0aeed30e072b, Call Stack: null, Custom Event ID: -1, Message: VM rnoriVM was started by admin (Host: venus-vdsa).
>2014-07-01 11:37:14,682 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-60) VM rnoriVM 2dfeb494-de75-4b7a-9295-76dd569fbe11 moved from WaitForLaunch --> PoweringUp
>2014-07-01 11:37:44,829 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-80) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:38:13,680 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-96) VM rnoriVM 2dfeb494-de75-4b7a-9295-76dd569fbe11 moved from PoweringUp --> Up
>2014-07-01 11:38:13,780 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-96) Correlation ID: 669527a3, Job ID: 53e7a254-d565-450d-b1e3-0aeed30e072b, Call Stack: null, Custom Event ID: -1, Message: VM rnoriVM started on Host venus-vdsa
>2014-07-01 11:38:44,888 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-37) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:39:44,359 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-23) [85f628] Running command: ShutdownVmCommand internal: false. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM
>2014-07-01 11:39:44,363 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-23) [85f628] Entered (VM rnoriVM).
>2014-07-01 11:39:44,364 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-23) [85f628] Sending shutdown command for VM rnoriVM.
>2014-07-01 11:39:44,367 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-23) [85f628] START, DestroyVmVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, force=false, secondsToWait=30, gracefully=true, reason=Liron), log id: 38dbf475
>2014-07-01 11:39:44,375 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-23) [85f628] START, DestroyVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, force=false, secondsToWait=30, gracefully=true, reason=Liron), log id: 3d13b430
>2014-07-01 11:39:44,385 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-23) [85f628] FINISH, DestroyVDSCommand, log id: 3d13b430
>2014-07-01 11:39:44,400 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-23) [85f628] FINISH, DestroyVmVDSCommand, return: PoweringDown, log id: 38dbf475
>2014-07-01 11:39:44,484 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-23) [85f628] Correlation ID: 85f628, Job ID: a8b8c6f8-2fc3-4481-8a24-f44fd3f9a425, Call Stack: null, Custom Event ID: -1, Message: VM shutdown initiated by admin on VM rnoriVM (Host: venus-vdsa) (Reason: Liron).
>2014-07-01 11:39:44,929 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-41) [66769267] Attempting to update VMs/Templates Ovf.
>2014-07-01 11:40:27,059 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-32) [1728f546] Running command: ShutdownVmCommand internal: false. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM
>2014-07-01 11:40:27,062 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-32) [1728f546] Entered (VM rnoriVM).
>2014-07-01 11:40:27,063 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-32) [1728f546] Cannot shutdown VM rnoriVM, status is not up. Stopping instead.
>2014-07-01 11:40:27,099 INFO  [org.ovirt.engine.core.bll.StopVmCommand] (org.ovirt.thread.pool-8-thread-32) [334df534] Running command: StopVmCommand internal: true. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM
>2014-07-01 11:40:27,104 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-32) [334df534] START, DestroyVmVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, force=false, secondsToWait=0, gracefully=false, reason=), log id: fff0146
>2014-07-01 11:40:27,111 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-32) [334df534] START, DestroyVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, force=false, secondsToWait=0, gracefully=false, reason=), log id: 35840c80
>2014-07-01 11:40:27,646 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-32) [334df534] FINISH, DestroyVDSCommand, log id: 35840c80
>2014-07-01 11:40:27,659 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-32) [334df534] FINISH, DestroyVmVDSCommand, return: Down, log id: fff0146
>2014-07-01 11:40:27,679 INFO  [org.ovirt.engine.core.bll.ProcessDownVmCommand] (org.ovirt.thread.pool-8-thread-34) [64b62ea2] Running command: ProcessDownVmCommand internal: true.
>2014-07-01 11:40:27,683 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-32) [334df534] Correlation ID: 334df534, Job ID: 7959a0cc-911b-40cd-a370-d9a6e4c04b67, Call Stack: null, Custom Event ID: -1, Message: VM rnoriVM was powered off ungracefully by admin (Host: venus-vdsa) (Reason: Not Specified).
>2014-07-01 11:40:35,452 WARN  [org.ovirt.engine.core.bll.ShutdownVmCommand] (http--0.0.0.0-8080-2) [30cd99c7] CanDoAction of action ShutdownVm failed. Reasons:VAR__ACTION__SHUTDOWN,VAR__TYPE__VM,ACTION_TYPE_FAILED_VM_IS_NOT_RUNNING
>2014-07-01 11:40:41,522 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (http--0.0.0.0-8080-2) [5ba41a03] Lock Acquired to object EngineLock [exclusiveLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM
>, sharedLocks= ]
>2014-07-01 11:40:41,617 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http--0.0.0.0-8080-2) [5ba41a03] START, IsVmDuringInitiatingVDSCommand( vmId = 2dfeb494-de75-4b7a-9295-76dd569fbe11), log id: 7c9f949f
>2014-07-01 11:40:41,619 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http--0.0.0.0-8080-2) [5ba41a03] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 7c9f949f
>2014-07-01 11:40:41,690 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-44) [5ba41a03] Running command: RunVmCommand internal: false. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM
>2014-07-01 11:40:41,736 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-44) [5ba41a03] START, UpdateVmDynamicDataVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmDynamic=org.ovirt.engine.core.common.businessentities.VmDynamic@b8a724dd), log id: 23d3b987
>2014-07-01 11:40:41,748 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-44) [5ba41a03] FINISH, UpdateVmDynamicDataVDSCommand, log id: 23d3b987
>2014-07-01 11:40:41,752 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-8-thread-44) [5ba41a03] START, CreateVmVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, vm=VM [rnoriVM]), log id: 169d565f
>2014-07-01 11:40:41,759 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-44) [5ba41a03] START, CreateVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, vm=VM [rnoriVM]), log id: 69053f40
>2014-07-01 11:40:41,793 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-44) [5ba41a03] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand memSize=1024,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=rhel6.5.0,keyboardLayout=en-us,memGuaranteedSize=1024,nice=0,display=qxl,smartcardEnable=false,bootMenuEnable=false,numaTune={mode=preferred},smpCoresPerSocket=1,maxVCpus=160,copyPasteEnable=true,timeOffset=0,transparentHugePages=true,vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11,devices=[{address={bus=0x00, domain=0x0000, slot=0x02, type=pci, function=0x0}, specParams={vram=32768, heads=1}, device=qxl, type=video, deviceId=b5f5f2cd-535b-4b77-940d-b508f035ae76}, {shared=false, iface=ide, index=2, address={unit=0, bus=1, target=0, controller=0, type=drive}, specParams={path=}, path=, device=cdrom, type=disk, readonly=true, deviceId=1aa45b98-a35b-4d7e-a234-3c3d83e16f4f}, {shared=false, index=0, volumeID=e414e87f-b6e6-41d4-a5a5-df5c242e6185, propagateErrors=off, format=raw, type=disk, iface=virtio, bootOrder=1, address={bus=0x00, domain=0x0000, slot=0x06, type=pci, function=0x0}, domainID=57f19479-c31a-4885-8529-1d0f1120a024, imageID=3c320707-752e-4364-9fc5-b489e0cccf97, specParams={}, optional=false, device=disk, poolID=af2c9167-5d67-4494-ae09-def33cf383c2, readonly=false, deviceId=3c320707-752e-4364-9fc5-b489e0cccf97}, {shared=false, volumeID=9792688f-a570-4ff4-98ca-911b6921b3e0, iface=virtio, imageID=60b052a9-8ca8-4855-ad23-efa9e79aab48, domainID=f0c702c0-aec8-49c2-a48c-0bf9e1fd447d, address={bus=0x00, domain=0x0000, slot=0x07, type=pci, function=0x0}, specParams={}, optional=false, propagateErrors=off, device=disk, poolID=af2c9167-5d67-4494-ae09-def33cf383c2, format=raw, type=disk, readonly=false, deviceId=60b052a9-8ca8-4855-ad23-efa9e79aab48}, {nicModel=pv, address={bus=0x00, domain=0x0000, slot=0x03, type=pci, function=0x0}, specParams={outbound={}, inbound={}}, macAddr=00:1a:4a:16:01:51, device=bridge, linkActive=true, type=interface, filter=vdsm-no-mac-spoofing, network=ovirtmgmt, deviceId=1fad0f83-a3aa-4c18-9daa-556a3bffd800}, {index=0, model=virtio-scsi, address={bus=0x00, domain=0x0000, slot=0x04, type=pci, function=0x0}, specParams={}, device=scsi, type=controller, deviceId=d6830aae-2bca-438f-9d39-d8c79651fc29}],acpiEnable=true,vmName=rnoriVM,fileTransferEnable=true,cpuType=Conroe,custom={device_c0c46599-e37d-4ffd-8c5a-986b23ae9452device_e7b46b5a-e55c-4546-951e-422758dc3bdadevice_08c16267-7dd4-4120-a5ff-2392b9dfb81cdevice_c31c802b-1e4a-4048-b5a7-6f27214496a7device_248b7f07-c876-4d9e-8eb7-aa31adbf9769=VmDevice {vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, deviceId=248b7f07-c876-4d9e-8eb7-aa31adbf9769, device=spicevmc, type=CHANNEL, bootOrder=0, specParams={}, address={port=3, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel2, customProperties={}, snapshotId=null}, device_c0c46599-e37d-4ffd-8c5a-986b23ae9452device_e7b46b5a-e55c-4546-951e-422758dc3bda=VmDevice {vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, deviceId=e7b46b5a-e55c-4546-951e-422758dc3bda, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x05, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}, snapshotId=null}, device_c0c46599-e37d-4ffd-8c5a-986b23ae9452device_e7b46b5a-e55c-4546-951e-422758dc3bdadevice_08c16267-7dd4-4120-a5ff-2392b9dfb81c=VmDevice {vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, deviceId=08c16267-7dd4-4120-a5ff-2392b9dfb81c, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=1, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel0, customProperties={}, snapshotId=null}, device_c0c46599-e37d-4ffd-8c5a-986b23ae9452=VmDevice {vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, deviceId=c0c46599-e37d-4ffd-8c5a-986b23ae9452, device=ide, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x1}, managed=false, plugged=true, readOnly=false, deviceAlias=ide0, customProperties={}, snapshotId=null}, device_c0c46599-e37d-4ffd-8c5a-986b23ae9452device_e7b46b5a-e55c-4546-951e-422758dc3bdadevice_08c16267-7dd4-4120-a5ff-2392b9dfb81cdevice_c31c802b-1e4a-4048-b5a7-6f27214496a7=VmDevice {vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, deviceId=c31c802b-1e4a-4048-b5a7-6f27214496a7, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=2, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel1, customProperties={}, snapshotId=null}}
>2014-07-01 11:40:41,840 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-44) [5ba41a03] FINISH, CreateVDSCommand, log id: 69053f40
>2014-07-01 11:40:41,857 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-8-thread-44) [5ba41a03] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 169d565f
>2014-07-01 11:40:41,858 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-44) [5ba41a03] Lock freed to object EngineLock [exclusiveLocks= key: 2dfeb494-de75-4b7a-9295-76dd569fbe11 value: VM
>, sharedLocks= ]
>2014-07-01 11:40:41,882 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-44) [5ba41a03] Correlation ID: 5ba41a03, Job ID: 3c5287a0-2b34-460d-a12e-da5a493a9f7a, Call Stack: null, Custom Event ID: -1, Message: VM rnoriVM was started by admin (Host: venus-vdsa).
>2014-07-01 11:40:43,771 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-85) VM rnoriVM 2dfeb494-de75-4b7a-9295-76dd569fbe11 moved from WaitForLaunch --> PoweringUp
>2014-07-01 11:40:44,983 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-3) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:41:42,622 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-22) VM rnoriVM 2dfeb494-de75-4b7a-9295-76dd569fbe11 moved from PoweringUp --> Up
>2014-07-01 11:41:42,720 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-22) Correlation ID: 5ba41a03, Job ID: 3c5287a0-2b34-460d-a12e-da5a493a9f7a, Call Stack: null, Custom Event ID: -1, Message: VM rnoriVM started on Host venus-vdsa
>2014-07-01 11:41:45,056 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-31) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:42:45,089 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-76) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:43:45,116 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-4) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:44:01,772 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-18) [1a87d44] Running command: ShutdownVmCommand internal: false. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM
>2014-07-01 11:44:01,775 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-18) [1a87d44] Entered (VM rnoriVM).
>2014-07-01 11:44:01,776 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-18) [1a87d44] Sending shutdown command for VM rnoriVM.
>2014-07-01 11:44:01,779 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-18) [1a87d44] START, DestroyVmVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, force=false, secondsToWait=30, gracefully=true, reason=Ravi), log id: 6aaa2a4
>2014-07-01 11:44:01,826 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-18) [1a87d44] START, DestroyVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, force=false, secondsToWait=30, gracefully=true, reason=Ravi), log id: 61134cde
>2014-07-01 11:44:01,874 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-18) [1a87d44] FINISH, DestroyVDSCommand, log id: 61134cde
>2014-07-01 11:44:01,888 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-18) [1a87d44] FINISH, DestroyVmVDSCommand, return: PoweringDown, log id: 6aaa2a4
>2014-07-01 11:44:01,905 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-18) [1a87d44] Correlation ID: 1a87d44, Job ID: 70e84af2-56dc-41d0-802d-71093f1f1490, Call Stack: null, Custom Event ID: -1, Message: VM shutdown initiated by admin on VM rnoriVM (Host: venus-vdsa) (Reason: Ravi).
>2014-07-01 11:44:45,166 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-47) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:45:45,204 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-71) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:47:45,392 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-33) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:49:45,560 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-2) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:50:45,594 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-56) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:51:14,583 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-3) [7b0d8f4e] Running command: ShutdownVmCommand internal: false. Entities affected :  ID: 2dfeb494-de75-4b7a-9295-76dd569fbe11 Type: VM
>2014-07-01 11:51:14,587 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-3) [7b0d8f4e] Entered (VM rnoriVM).
>2014-07-01 11:51:14,588 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-3) [7b0d8f4e] Sending shutdown command for VM rnoriVM.
>2014-07-01 11:51:14,591 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-3) [7b0d8f4e] START, DestroyVmVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, force=false, secondsToWait=30, gracefully=true, reason=Ravi2), log id: 1f338ef5
>2014-07-01 11:51:14,599 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-3) [7b0d8f4e] START, DestroyVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=2dfeb494-de75-4b7a-9295-76dd569fbe11, force=false, secondsToWait=30, gracefully=true, reason=Ravi2), log id: 7f92351e
>2014-07-01 11:51:14,649 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-3) [7b0d8f4e] FINISH, DestroyVDSCommand, log id: 7f92351e
>2014-07-01 11:51:14,658 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-3) [7b0d8f4e] FINISH, DestroyVmVDSCommand, return: PoweringDown, log id: 1f338ef5
>2014-07-01 11:51:14,676 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-3) [7b0d8f4e] Correlation ID: 7b0d8f4e, Job ID: 3ed48023-172c-4495-b34c-28ce0a13d71f, Call Stack: null, Custom Event ID: -1, Message: VM shutdown initiated by admin on VM rnoriVM (Host: venus-vdsa) (Reason: Ravi2).
>2014-07-01 11:51:45,693 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-89) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:52:45,749 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-39) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:53:45,786 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-59) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:54:45,865 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-9) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:55:45,955 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-43) Attempting to update VMs/Templates Ovf.
>2014-07-01 11:56:15,297 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-62) VM rnoriVM 2dfeb494-de75-4b7a-9295-76dd569fbe11 moved from PoweringDown --> Up
>2014-07-01 11:56:15,329 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-62) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Shutdown of VM rnoriVM failed.

Actions: View

Attachments on bug 1114903: 913707 | 922856