Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 913707 Details for
Bug 1114903
Reason does not get updated when trying to shut down a VM which is Powering up of Shutting Down
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
engine.log
engine.log (text/x-log), 116.18 KB, created by
Maor
on 2014-07-01 08:56:19 UTC
(
hide
)
Description:
engine.log
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.
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 1114903
: 913707 |
922856