<html><head></head><body><div style="color:#000; background-color:#fff; font-family:Helvetica Neue, Helvetica, Arial, Lucida Grande, sans-serif;font-size:13px"><div id="yiv7471100670"><div id="yui_3_16_0_ym19_1_1497684527403_3564"><div style="color:#000;background-color:#fff;font-family:Helvetica Neue, Helvetica, Arial, Lucida Grande, sans-serif;font-size:13px;" id="yui_3_16_0_ym19_1_1497684527403_3563"><div id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_3877">Dear Martin<br></div><div class="yiv7471100670signature" id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_3829"><div id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_3828">
<div id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4073"><br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4074">
</div>
<div id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4075"><br></div><div id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4075">My Ovirt Engine Log In The Time When Ansible Script Run Can You Please check the log :<br></div>
<div dir="ltr" id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4076"><br></div><div dir="ltr" id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4076"><br></div><div dir="ltr" id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4076"><br></div><div dir="ltr" id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4076">2017-06-17 10:13:53,478 INFO
[org.ovirt.engine.core.sso.utils.AuthenticationUtils] (default task-5)
[] User admin@internal successfully logged in with scopes: ovirt-app-api
ovirt-ext=token-info:authz-search
ovirt-ext=token-info:public-authz-search ovirt-ext=token-info:validate<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4077">
2017-06-17 10:13:54,076 INFO
[org.ovirt.engine.core.bll.aaa.CreateUserSessionCommand] (default
task-2) [2cefa073] Running command: CreateUserSessionCommand internal:
false.<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4078">
2017-06-17 10:13:54,227 INFO [org.ovirt.engine.core.bll.AddVmCommand]
(default task-1) [22a2647d] Lock Acquired to object
'EngineLock:{exclusiveLocks='[myvm05=<VM_NAME,
ACTION_TYPE_FAILED_OBJECT_LOCKED>]',
sharedLocks='[71e0d46a-d8b8-48dc-bae4-3895a42fa005=<TEMPLATE,
ACTION_TYPE_FAILED_TEMPLATE_IS_USED_FOR_CREATE_VM$VmName myvm05>,
4aba3e03-6665-46d5-882c-8d8931a563f0=<DISK,
ACTION_TYPE_FAILED_DISK_IS_USED_FOR_CREATE_VM$VmName myvm05>]'}'<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4079">
2017-06-17 10:13:54,287 INFO [org.ovirt.engine.core.bll.AddVmCommand]
(default task-1) [22a2647d] Running command: AddVmCommand internal:
false. Entities affected : ID: ffc26dac-0aeb-4486-8ce4-6eada0a99f0a
Type: ClusterAction group CREATE_VM with role type USER, ID:
71e0d46a-d8b8-48dc-bae4-3895a42fa005 Type: VmTemplateAction group
CREATE_VM with role type USER, ID: 4a62e1c2-8942-48f6-a3c4-1e54d158e13a
Type: StorageAction group CREATE_DISK with role type USER<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4080">
2017-06-17 10:13:54,362 INFO
[org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (default task-1)
[] START, SetVmStatusVDSCommand(
SetVmStatusVDSCommandParameters:{runAsync='true',
vmId='535f7d2b-a3df-4f7e-91e1-7b564bff625c', status='ImageLocked',
exitStatus='Normal'}), log id: 758c11b1<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4081">
2017-06-17 10:13:54,366 INFO
[org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (default task-1)
[] FINISH, SetVmStatusVDSCommand, log id: 758c11b1<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4082">
2017-06-17 10:13:54,375 INFO
[org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
(default task-1) [431bbdcb] Running command:
CreateSnapshotFromTemplateCommand internal: true. Entities affected :
ID: 4a62e1c2-8942-48f6-a3c4-1e54d158e13a Type: Storage<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4083">
2017-06-17 10:13:54,389 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand]
(default task-1) [431bbdcb] START, CreateSnapshotVDSCommand(
CreateSnapshotVDSCommandParameters:{runAsync='true',
storagePoolId='ca3d926a-fff6-41e3-bddd-f244289713fc',
ignoreFailoverLimit='false',
storageDomainId='4a62e1c2-8942-48f6-a3c4-1e54d158e13a',
imageGroupId='67bfd3f8-8def-4b3a-bb45-c52aed5d1a4f',
imageSizeInBytes='17179869184', volumeFormat='COW',
newImageId='b5142477-2134-470e-8ba4-a2e72119ec9a',
newImageDescription='', imageInitialSizeInBytes='0',
imageId='901367d7-8a2a-4f7e-80e8-636738ea3990',
sourceImageGroupId='4aba3e03-6665-46d5-882c-8d8931a563f0'}), log id:
1931032<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4084">
2017-06-17 10:13:54,390 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand]
(default task-1) [431bbdcb] -- executeIrsBrokerCommand: calling
'createVolume' with two new parameters: description and UUID<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4085">
2017-06-17 10:13:55,518 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand]
(default task-1) [431bbdcb] FINISH, CreateSnapshotVDSCommand, return:
b5142477-2134-470e-8ba4-a2e72119ec9a, log id: 1931032<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4086">
2017-06-17 10:13:55,521 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (default task-1)
[431bbdcb] CommandAsyncTask::Adding CommandMultiAsyncTasks object for
command 'b35f7ba3-89fc-4720-86e2-98cfa2ac4aeb'<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4087">
2017-06-17 10:13:55,521 INFO
[org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (default task-1)
[431bbdcb] CommandMultiAsyncTasks::attachTask: Attaching task
'a236b425-4414-49d8-860b-a0e932db175f' to command
'b35f7ba3-89fc-4720-86e2-98cfa2ac4aeb'.<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4088">
2017-06-17 10:13:55,529 INFO
[org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (default task-1)
[431bbdcb] Adding task 'a236b425-4414-49d8-860b-a0e932db175f' (Parent
Command 'CreateSnapshotFromTemplate', Parameters Type
'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling
hasn't started yet..<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4089">
2017-06-17 10:13:55,571 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(default task-1) [431bbdcb] Correlation ID: 22a2647d, Job ID:
638b7524-baaa-4bb2-bb36-e02b78bb5dfd, Call Stack: null, Custom Event ID:
-1, Message: VM myvm05 creation was initiated by admin@internal-authz.<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4090">
2017-06-17 10:13:55,571 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (default task-1)
[431bbdcb] BaseAsyncTask::startPollingTask: Starting to poll task
'a236b425-4414-49d8-860b-a0e932db175f'.<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4091">
2017-06-17 10:13:57,274 INFO
[org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
(DefaultQuartzScheduler5) [431bbdcb] Command 'AddVm' (id:
'd089dd43-f977-4eb7-aa22-245d8ab69e5d') waiting on child command id:
'b35f7ba3-89fc-4720-86e2-98cfa2ac4aeb' type:'CreateSnapshotFromTemplate'
to complete<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4092">
2017-06-17 10:13:58,289 INFO
[org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
(DefaultQuartzScheduler8) [3a68eba3] Polling and updating Async Tasks: 2
tasks, 1 tasks to poll now<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4093">
2017-06-17 10:13:58,586 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler8)
[3a68eba3] SPMAsyncTask::PollTask: Polling task
'a236b425-4414-49d8-860b-a0e932db175f' (Parent Command
'CreateSnapshotFromTemplate', Parameters Type
'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned
status 'finished', result 'success'.<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4094">
2017-06-17 10:13:58,589 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler8)
[3a68eba3] BaseAsyncTask::onTaskEndSuccess: Task
'a236b425-4414-49d8-860b-a0e932db175f' (Parent Command
'CreateSnapshotFromTemplate', Parameters Type
'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended
successfully.<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4095">
2017-06-17 10:13:58,591 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(DefaultQuartzScheduler8) [3a68eba3]
CommandAsyncTask::endActionIfNecessary: All tasks of command
'b35f7ba3-89fc-4720-86e2-98cfa2ac4aeb' has ended -> executing
'endAction'<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4096">
2017-06-17 10:13:58,591 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(DefaultQuartzScheduler8) [3a68eba3] CommandAsyncTask::endAction: Ending
action for '1' tasks (command ID:
'b35f7ba3-89fc-4720-86e2-98cfa2ac4aeb'): calling endAction '.<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4097">
2017-06-17 10:13:58,591 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(org.ovirt.thread.pool-6-thread-38) [3a68eba3]
CommandAsyncTask::endCommandAction [within thread] context: Attempting
to endAction 'CreateSnapshotFromTemplate', executionIndex: '0'<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4098">
2017-06-17 10:13:58,596 INFO
[org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
(org.ovirt.thread.pool-6-thread-38) [431bbdcb] Command
[id=b35f7ba3-89fc-4720-86e2-98cfa2ac4aeb]: Updating status to
'SUCCEEDED', The command end method logic will be executed by one of its
parent commands.<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4099">
2017-06-17 10:13:58,597 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(org.ovirt.thread.pool-6-thread-38) [431bbdcb]
CommandAsyncTask::HandleEndActionResult [within thread]: endAction for
action type 'CreateSnapshotFromTemplate' completed, handling the result.<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4100">
2017-06-17 10:13:58,597 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(org.ovirt.thread.pool-6-thread-38) [431bbdcb]
CommandAsyncTask::HandleEndActionResult [within thread]: endAction for
action type 'CreateSnapshotFromTemplate' succeeded, clearing tasks.<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4101">
2017-06-17 10:13:58,597 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(org.ovirt.thread.pool-6-thread-38) [431bbdcb]
SPMAsyncTask::ClearAsyncTask: Attempting to clear task
'a236b425-4414-49d8-860b-a0e932db175f'<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4102">
2017-06-17 10:13:58,598 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
(org.ovirt.thread.pool-6-thread-38) [431bbdcb] START,
SPMClearTaskVDSCommand(
SPMTaskGuidBaseVDSCommandParameters:{runAsync='true',
storagePoolId='ca3d926a-fff6-41e3-bddd-f244289713fc',
ignoreFailoverLimit='false',
taskId='a236b425-4414-49d8-860b-a0e932db175f'}), log id: 37a1669b<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4103">
2017-06-17 10:13:58,598 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
(org.ovirt.thread.pool-6-thread-38) [431bbdcb] START,
HSMClearTaskVDSCommand(HostName = h1,
HSMTaskGuidBaseVDSCommandParameters:{runAsync='true',
hostId='e62505b1-d9b6-4231-992c-8e4851c66624',
taskId='a236b425-4414-49d8-860b-a0e932db175f'}), log id: 5ecec77e<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4104">
2017-06-17 10:13:59,633 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
(org.ovirt.thread.pool-6-thread-38) [431bbdcb] FINISH,
HSMClearTaskVDSCommand, log id: 5ecec77e<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4105">
2017-06-17 10:13:59,633 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
(org.ovirt.thread.pool-6-thread-38) [431bbdcb] FINISH,
SPMClearTaskVDSCommand, log id: 37a1669b<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4106">
2017-06-17 10:13:59,635 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(org.ovirt.thread.pool-6-thread-38) [431bbdcb]
BaseAsyncTask::removeTaskFromDB: Removed task
'a236b425-4414-49d8-860b-a0e932db175f' from DataBase<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4107">
2017-06-17 10:13:59,635 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(org.ovirt.thread.pool-6-thread-38) [431bbdcb]
CommandAsyncTask::HandleEndActionResult [within thread]: Removing
CommandMultiAsyncTasks object for entity
'b35f7ba3-89fc-4720-86e2-98cfa2ac4aeb'<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4108">
2017-06-17 10:14:01,284 INFO
[org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
(DefaultQuartzScheduler7) [431bbdcb] Command 'AddVm' id:
'd089dd43-f977-4eb7-aa22-245d8ab69e5d' child commands
'[b35f7ba3-89fc-4720-86e2-98cfa2ac4aeb]' executions were completed,
status 'SUCCEEDED'<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4109">
2017-06-17 10:14:02,299 INFO [org.ovirt.engine.core.bll.AddVmCommand]
(DefaultQuartzScheduler2) [431bbdcb] Ending command
'org.ovirt.engine.core.bll.AddVmCommand' successfully.<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4110">
2017-06-17 10:14:02,304 INFO
[org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
(DefaultQuartzScheduler2) [431bbdcb] Ending command
'org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand'
successfully.<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4111">
2017-06-17 10:14:02,309 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand]
(DefaultQuartzScheduler2) [431bbdcb] START, GetImageInfoVDSCommand(
GetImageInfoVDSCommandParameters:{runAsync='true',
storagePoolId='ca3d926a-fff6-41e3-bddd-f244289713fc',
ignoreFailoverLimit='false',
storageDomainId='4a62e1c2-8942-48f6-a3c4-1e54d158e13a',
imageGroupId='67bfd3f8-8def-4b3a-bb45-c52aed5d1a4f',
imageId='b5142477-2134-470e-8ba4-a2e72119ec9a'}), log id: 6fd9785f<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4112">
2017-06-17 10:14:03,344 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand]
(DefaultQuartzScheduler2) [431bbdcb] FINISH, GetImageInfoVDSCommand,
return:
org.ovirt.engine.core.common.businessentities.storage.DiskImage@22deef7c,
log id: 6fd9785f<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4113">
2017-06-17 10:14:03,356 INFO
[org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand]
(DefaultQuartzScheduler2) [] START, SetVmStatusVDSCommand(
SetVmStatusVDSCommandParameters:{runAsync='true',
vmId='535f7d2b-a3df-4f7e-91e1-7b564bff625c', status='Down',
exitStatus='Normal'}), log id: 44ed9ea6<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4114">
2017-06-17 10:14:03,358 INFO
[org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand]
(DefaultQuartzScheduler2) [] FINISH, SetVmStatusVDSCommand, log id:
44ed9ea6<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4115">
2017-06-17 10:14:03,362 INFO [org.ovirt.engine.core.bll.AddVmCommand]
(DefaultQuartzScheduler2) [] Lock freed to object
'EngineLock:{exclusiveLocks='[myvm05=<VM_NAME,
ACTION_TYPE_FAILED_OBJECT_LOCKED>]',
sharedLocks='[71e0d46a-d8b8-48dc-bae4-3895a42fa005=<TEMPLATE,
ACTION_TYPE_FAILED_TEMPLATE_IS_USED_FOR_CREATE_VM$VmName myvm05>,
4aba3e03-6665-46d5-882c-8d8931a563f0=<DISK,
ACTION_TYPE_FAILED_DISK_IS_USED_FOR_CREATE_VM$VmName myvm05>]'}'<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4116">
2017-06-17 10:14:03,370 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(DefaultQuartzScheduler2) [] Correlation ID: 22a2647d, Job ID:
638b7524-baaa-4bb2-bb36-e02b78bb5dfd, Call Stack: null, Custom Event ID:
-1, Message: VM myvm05 creation has been completed.<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4117">
2017-06-17 10:14:04,768 INFO
[org.ovirt.engine.core.bll.RunVmOnceCommand] (default task-19) [590cac7]
Lock Acquired to object
'EngineLock:{exclusiveLocks='[535f7d2b-a3df-4f7e-91e1-7b564bff625c=<VM,
ACTION_TYPE_FAILED_OBJECT_LOCKED>]', sharedLocks='null'}'<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4118">
2017-06-17 10:14:04,777 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(default task-19) [590cac7] START, IsVmDuringInitiatingVDSCommand(
IsVmDuringInitiatingVDSCommandParameters:{runAsync='true',
vmId='535f7d2b-a3df-4f7e-91e1-7b564bff625c'}), log id: 261674f1<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4119">
2017-06-17 10:14:04,777 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(default task-19) [590cac7] FINISH, IsVmDuringInitiatingVDSCommand,
return: false, log id: 261674f1<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4120">
2017-06-17 10:14:04,798 INFO
[org.ovirt.engine.core.bll.RunVmOnceCommand] (default task-19) [590cac7]
Running command: RunVmOnceCommand internal: false. Entities affected :
ID: 535f7d2b-a3df-4f7e-91e1-7b564bff625c Type: VMAction group RUN_VM
with role type USER<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4121">
2017-06-17 10:14:04,817 INFO
[org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (default
task-19) [590cac7] START, UpdateVmDynamicDataVDSCommand(
UpdateVmDynamicDataVDSCommandParameters:{runAsync='true', hostId='null',
vmId='00000000-0000-0000-0000-000000000000',
vmDynamic='org.ovirt.engine.core.common.businessentities.VmDynamic@e1224d5b'}),
log id: bc72f78<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4122">
2017-06-17 10:14:04,820 INFO
[org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (default
task-19) [590cac7] FINISH, UpdateVmDynamicDataVDSCommand, log id:
bc72f78<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4123">
2017-06-17 10:14:04,822 INFO
[org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (default task-19)
[590cac7] START, CreateVmVDSCommand(
CreateVmVDSCommandParameters:{runAsync='true',
hostId='e62505b1-d9b6-4231-992c-8e4851c66624',
vmId='535f7d2b-a3df-4f7e-91e1-7b564bff625c', vm='VM [myvm05]'}), log id:
5d92bbd3<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4124">
2017-06-17 10:14:04,825 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromCloudInitVDSCommand]
(default task-19) [590cac7] START,
CreateVmFromCloudInitVDSCommand(HostName = h1,
CreateVmVDSCommandParameters:{runAsync='true',
hostId='e62505b1-d9b6-4231-992c-8e4851c66624',
vmId='535f7d2b-a3df-4f7e-91e1-7b564bff625c', vm='VM [myvm05]'}), log id:
7b28f107<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4125">
2017-06-17 10:14:04,828 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.VmInfoBuilderBase] (default
task-19) [590cac7] Bootable disk '67bfd3f8-8def-4b3a-bb45-c52aed5d1a4f'
set to index '0'<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4126">
2017-06-17 10:14:04,842 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (default
task-19) [590cac7]
org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromCloudInitVDSCommand
pitReinjection=false,memGuaranteedSize=2000,smpThreadsPerCore=1,cpuType=Westmere,vmId=535f7d2b-a3df-4f7e-91e1-7b564bff625c,acpiEnable=true,vmType=kvm,smp=8,smpCoresPerSocket=2,emulatedMachine=pc-i440fx-rhel7.2.0,smartcardEnable=false,guestNumaNodes=[{memory=4096,
cpus=0,1,2,3,4,5,6,7,
nodeIndex=0}],transparentHugePages=true,displayNetwork=ovirtmgmt,vmName=myvm05,maxVCpus=32,kvmEnable=true,devices=[{iface=ide,
shared=false, path=, readonly=true, index=3, type=disk,
specParams={vmPayload={file={openstack/latest/meta_data.json=ewogICJuZXR3b3JrLWludGVyZmFjZXMiIDogImF1dG8gZXRoMFxuaWZhY2UgZXRoMCBpbmV0IHN0YXRpY1xuICBhZGRyZXNzIDEwLjEwLjEwLjUwXG4gIG5ldG1hc2sgMjU1LjI1NS4yNTUuMFxuICBnYXRld2F5IDEwLjEwLjEwLjFcbiAgZG5zLW5hbWVzZXJ2ZXJzIDEwOS4yMjQuMTQuMlxuICBkbnMtc2VhcmNoIGVsY2xkLm5ldFxuIiwKICAiYXZhaWxhYmlsaXR5X3pvbmUiIDogIm5vdmEiLAogICJob3N0bmFtZSIgOiAidm0wMSIsCiAgImxhdW5jaF9pbmRleCIgOiAiMCIsCiAgIm1ldGEiIDogewogICAgInJvbGUiIDogInNlcnZlciIsCiAgICAiZHNtb2RlIiA6ICJsb2NhbCIsCiAgICAiZXNzZW50aWFsIiA6ICJmYWxzZSIKICB9LAogICJuYW1lIiA6ICJ2bTAxIiwKICAibmV0d29ya19jb25maWciIDogewogICAgInBhdGgiIDogIi9ldGMvbmV0d29yay9pbnRlcmZhY2VzIiwKICAgICJjb250ZW50X3BhdGgiIDogIi9jb250ZW50LzAwMDAiCiAgfSwKICAidXVpZCIgOiAiNjdjOThkOWQtOWZlOC00ZWU2LTg0NGUtZjU4N2Y1Njc5NWExIgp9,
openstack/content/0000=YXV0byBldGgwCmlmYWNlIGV0aDAgaW5ldCBzdGF0aWMKICBhZGRyZXNzIDEwLjEwLjEwLjUwCiAgbmV0bWFzayAyNTUuMjU1LjI1NS4wCiAgZ2F0ZXdheSAxMC4xMC4xMC4xCiAgZG5zLW5hbWVzZXJ2ZXJzIDEwOS4yMjQuMTQuMgogIGRucy1zZWFyY2ggZWxjbGQubmV0Cg==,
openstack/latest/user_data=I2Nsb3VkLWNvbmZpZwpvdXRwdXQ6CiAgYWxsOiAnPj4gL3Zhci9sb2cvY2xvdWQtaW5pdC1vdXRwdXQubG9nJwpwYXNzd29yZDogYm9vYm9vCmRpc2FibGVfcm9vdDogMApydW5jbWQ6Ci0gJ3NlZCAtaSAnJy9eZGF0YXNvdXJjZV9saXN0OiAvZCcnIC9ldGMvY2xvdWQvY2xvdWQuY2ZnOyBlY2hvICcnZGF0YXNvdXJjZV9saXN0OgogIFsiTm9DbG91ZCIsICJDb25maWdEcml2ZSJdJycgPj4gL2V0Yy9jbG91ZC9jbG91ZC5jZmcnCnNzaF9wd2F1dGg6IHRydWUKY2hwYXNzd2Q6CiAgZXhwaXJlOiBmYWxzZQp1c2VyOiByb290Cg==},
volId=config-2}}, device=cdrom,
deviceId=95cee92c-3f8d-4b2b-93f9-679c854f84b0}, {type=video,
specParams={vgamem=16384, heads=1, vram=32768, ram=65536}, device=qxl,
deviceId=5cb25f3c-dccb-4a8e-87d7-5873db429f4e}, {type=graphics,
specParams={spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,ssmartcard,susbredir,
fileTransferEnable=true, spiceSslCipherSuite=DEFAULT,
copyPasteEnable=true}, device=spice,
deviceId=4f91a674-25e7-45be-92a6-50285b15d130}, {iface=ide,
shared=false, path=, address={bus=1, controller=0, unit=0, type=drive,
target=0}, readonly=true, index=2, type=disk, specParams={path=},
device=cdrom, deviceId=abc8d3e6-ef97-4e2a-9559-ee62519a8e81},
{shared=false, address={bus=0x00, domain=0x0000, function=0x0,
slot=0x06, type=pci}, imageID=67bfd3f8-8def-4b3a-bb45-c52aed5d1a4f,
format=cow, index=0, optional=false, type=disk,
deviceId=67bfd3f8-8def-4b3a-bb45-c52aed5d1a4f,
domainID=4a62e1c2-8942-48f6-a3c4-1e54d158e13a, propagateErrors=off,
iface=virtio, readonly=false, bootOrder=1,
poolID=ca3d926a-fff6-41e3-bddd-f244289713fc,
volumeID=b5142477-2134-470e-8ba4-a2e72119ec9a, specParams={},
device=disk}, {filter=vdsm-no-mac-spoofing, nicModel=pv,
address={bus=0x00, domain=0x0000, function=0x0, slot=0x03, type=pci},
type=interface, specParams={inbound={}, outbound={}}, device=bridge,
linkActive=true, deviceId=d90c311d-1d07-445d-bc0c-93da4fc38327,
macAddr=00:1a:4a:16:01:69, network=ovirtmgmt}, {address={bus=0x00,
function=0x0, domain=0x0000, slot=0x07, type=pci}, type=balloon,
specParams={model=virtio}, device=memballoon,
deviceId=dcf2375e-54b2-471f-9dba-a0c7d509492f}, {index=0,
model=virtio-scsi, type=controller, specParams={}, device=scsi,
deviceId=e1eb89e1-77df-4677-ba9a-4b3c92a46e69}, {address={bus=0x00,
domain=0x0000, function=0x0, slot=0x05, type=pci}, type=controller,
specParams={}, device=virtio-serial,
deviceId=3d096c2f-fdbd-43dd-8243-11e562e0a612}],custom={device_966e95b5-6a22-4836-aad0-c9f4bb79389e=VmDevice:{id='VmDeviceId:{deviceId='966e95b5-6a22-4836-aad0-c9f4bb79389e',
vmId='535f7d2b-a3df-4f7e-91e1-7b564bff625c'}', device='unix',
type='CHANNEL', bootOrder='0', specParams='[]', address='{bus=0,
controller=0, type=virtio-serial, port=1}', managed='false',
plugged='true', readOnly='false', deviceAlias='channel0',
customProperties='[]', snapshotId='null', logicalName='null',
usingScsiReservation='false', hostDevice='null'},
device_966e95b5-6a22-4836-aad0-c9f4bb79389edevice_5172a707-33ea-4f05-855b-40232655ccb6=VmDevice:{id='VmDeviceId:{deviceId='5172a707-33ea-4f05-855b-40232655ccb6',
vmId='535f7d2b-a3df-4f7e-91e1-7b564bff625c'}', device='spicevmc',
type='CHANNEL', bootOrder='0', specParams='[]', address='{bus=0,
controller=0, type=virtio-serial, port=3}', managed='false',
plugged='true', readOnly='false', deviceAlias='channel2',
customProperties='[]', snapshotId='null', logicalName='null',
usingScsiReservation='false', hostDevice='null'},
device_966e95b5-6a22-4836-aad0-c9f4bb79389edevice_5172a707-33ea-4f05-855b-40232655ccb6device_60409373-848b-43e5-8c8c-117688b0ba71=VmDevice:{id='VmDeviceId:{deviceId='60409373-848b-43e5-8c8c-117688b0ba71',
vmId='535f7d2b-a3df-4f7e-91e1-7b564bff625c'}', device='ide',
type='CONTROLLER', bootOrder='0', specParams='[]', address='{slot=0x01,
bus=0x00, domain=0x0000, type=pci, function=0x1}', managed='false',
plugged='true', readOnly='false', deviceAlias='ide',
customProperties='[]', snapshotId='null', logicalName='null',
usingScsiReservation='false', hostDevice='null'},
device_966e95b5-6a22-4836-aad0-c9f4bb79389edevice_5172a707-33ea-4f05-855b-40232655ccb6device_60409373-848b-43e5-8c8c-117688b0ba71device_63038946-00aa-4b0a-9045-5dfb47b31e89=VmDevice:{id='VmDeviceId:{deviceId='63038946-00aa-4b0a-9045-5dfb47b31e89',
vmId='535f7d2b-a3df-4f7e-91e1-7b564bff625c'}', device='unix',
type='CHANNEL', bootOrder='0', specParams='[]', address='{bus=0,
controller=0, type=virtio-serial, port=2}', managed='false',
plugged='true', readOnly='false', deviceAlias='channel1',
customProperties='[]', snapshotId='null', logicalName='null',
usingScsiReservation='false',
hostDevice='null'}},display=qxl,timeOffset=0,nice=0,maxMemSize=4194304,maxMemSlots=16,bootMenuEnable=false,memSize=4096<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4127">
2017-06-17 10:14:04,856 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromCloudInitVDSCommand]
(default task-19) [590cac7] FINISH, CreateVmFromCloudInitVDSCommand,
log id: 7b28f107<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4128">
2017-06-17 10:14:04,860 INFO
[org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (default task-19)
[590cac7] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id:
5d92bbd3<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4129">
2017-06-17 10:14:04,860 INFO
[org.ovirt.engine.core.bll.RunVmOnceCommand] (default task-19) [590cac7]
Lock freed to object
'EngineLock:{exclusiveLocks='[535f7d2b-a3df-4f7e-91e1-7b564bff625c=<VM,
ACTION_TYPE_FAILED_OBJECT_LOCKED>]', sharedLocks='null'}'<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4130">
2017-06-17 10:14:04,863 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(default task-19) [590cac7] Correlation ID: 590cac7, Job ID:
a66974f1-d8f1-477d-85cc-51c3ef70ebdb, Call Stack: null, Custom Event ID:
-1, Message: VM myvm05 was started by admin@internal-authz (Host: h1).<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4131">
2017-06-17 10:14:06,360 INFO
[org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer]
(ForkJoinPool-1-worker-1) [] VM
'535f7d2b-a3df-4f7e-91e1-7b564bff625c'(myvm05) moved from
'WaitForLaunch' --> 'PoweringUp'<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4132">
2017-06-17 10:14:06,367 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVDSCommand]
(ForkJoinPool-1-worker-1) [] START, FullListVDSCommand(HostName = ,
FullListVDSCommandParameters:{runAsync='true',
hostId='e62505b1-d9b6-4231-992c-8e4851c66624',
vds='Host[,e62505b1-d9b6-4231-992c-8e4851c66624]',
vmIds='[535f7d2b-a3df-4f7e-91e1-7b564bff625c]'}), log id: 3a801479<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4133">
2017-06-17 10:14:06,376 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVDSCommand]
(ForkJoinPool-1-worker-1) [] FINISH, FullListVDSCommand, return:
[{acpiEnable=true, emulatedMachine=pc-i440fx-rhel7.2.0,
vmId=535f7d2b-a3df-4f7e-91e1-7b564bff625c, guestDiskMapping={},
transparentHugePages=true, timeOffset=0, cpuType=Westmere, smp=8,
pauseCode=NOERR, guestNumaNodes=[Ljava.lang.Object;@8a07465,
smartcardEnable=false,
custom={device_966e95b5-6a22-4836-aad0-c9f4bb79389edevice_5172a707-33ea-4f05-855b-40232655ccb6device_60409373-848b-43e5-8c8c-117688b0ba71=VmDevice:{id='VmDeviceId:{deviceId='60409373-848b-43e5-8c8c-117688b0ba71',
vmId='535f7d2b-a3df-4f7e-91e1-7b564bff625c'}', device='ide',
type='CONTROLLER', bootOrder='0', specParams='[]', address='{slot=0x01,
bus=0x00, domain=0x0000, type=pci, function=0x1}', managed='false',
plugged='true', readOnly='false', deviceAlias='ide',
customProperties='[]', snapshotId='null', logicalName='null',
usingScsiReservation='false', hostDevice='null'},
device_966e95b5-6a22-4836-aad0-c9f4bb79389edevice_5172a707-33ea-4f05-855b-40232655ccb6=VmDevice:{id='VmDeviceId:{deviceId='5172a707-33ea-4f05-855b-40232655ccb6',
vmId='535f7d2b-a3df-4f7e-91e1-7b564bff625c'}', device='spicevmc',
type='CHANNEL', bootOrder='0', specParams='[]', address='{bus=0,
controller=0, type=virtio-serial, port=3}', managed='false',
plugged='true', readOnly='false', deviceAlias='channel2',
customProperties='[]', snapshotId='null', logicalName='null',
usingScsiReservation='false', hostDevice='null'},
device_966e95b5-6a22-4836-aad0-c9f4bb79389edevice_5172a707-33ea-4f05-855b-40232655ccb6device_60409373-848b-43e5-8c8c-117688b0ba71device_63038946-00aa-4b0a-9045-5dfb47b31e89=VmDevice:{id='VmDeviceId:{deviceId='63038946-00aa-4b0a-9045-5dfb47b31e89',
vmId='535f7d2b-a3df-4f7e-91e1-7b564bff625c'}', device='unix',
type='CHANNEL', bootOrder='0', specParams='[]', address='{bus=0,
controller=0, type=virtio-serial, port=2}', managed='false',
plugged='true', readOnly='false', deviceAlias='channel1',
customProperties='[]', snapshotId='null', logicalName='null',
usingScsiReservation='false', hostDevice='null'},
device_966e95b5-6a22-4836-aad0-c9f4bb79389e=VmDevice:{id='VmDeviceId:{deviceId='966e95b5-6a22-4836-aad0-c9f4bb79389e',
vmId='535f7d2b-a3df-4f7e-91e1-7b564bff625c'}', device='unix',
type='CHANNEL', bootOrder='0', specParams='[]', address='{bus=0,
controller=0, type=virtio-serial, port=1}', managed='false',
plugged='true', readOnly='false', deviceAlias='channel0',
customProperties='[]', snapshotId='null', logicalName='null',
usingScsiReservation='false', hostDevice='null'}}, vmType=kvm,
memSize=4096, smpCoresPerSocket=2, vmName=myvm05, nice=0, status=Up,
maxMemSize=4194304, bootMenuEnable=false, pid=1569, smpThreadsPerCore=1,
memGuaranteedSize=2000, kvmEnable=true, pitReinjection=false,
displayNetwork=ovirtmgmt, devices=[Ljava.lang.Object;@690fc889,
display=qxl, maxVCpus=32, clientIp=, statusTime=4467062130,
maxMemSlots=16}], log id: 3a801479<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4134">
2017-06-17 10:14:06,379 INFO
[org.ovirt.engine.core.vdsbroker.monitoring.VmDevicesMonitoring]
(ForkJoinPool-1-worker-1) [] Received a spice Device without an address
when processing VM 535f7d2b-a3df-4f7e-91e1-7b564bff625c devices,
skipping device: {device=spice, specParams={fileTransferEnable=true,
displayNetwork=ovirtmgmt, displayIp=192.168.215.215,
spiceSslCipherSuite=DEFAULT,
spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,ssmartcard,susbredir,
copyPasteEnable=true}, type=graphics,
deviceId=4f91a674-25e7-45be-92a6-50285b15d130, tlsPort=5901}<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4135">
2017-06-17 10:14:13,332 INFO
[org.ovirt.engine.core.vdsbroker.monitoring.VmsStatisticsFetcher]
(DefaultQuartzScheduler6) [3465d470] Fetched 2 VMs from VDS
'e62505b1-d9b6-4231-992c-8e4851c66624'<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4136">
2017-06-17 10:14:54,749 INFO
[org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
(DefaultQuartzScheduler4) [1a9e3b34] Setting new tasks map. The map
contains now 1 tasks<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4137">
2017-06-17 10:15:13,767 INFO
[org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer]
(DefaultQuartzScheduler9) [3a68eba3] VM
'535f7d2b-a3df-4f7e-91e1-7b564bff625c'(myvm05) moved from 'PoweringUp'
--> 'Up'<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4138">
2017-06-17 10:15:13,780 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(DefaultQuartzScheduler9) [3a68eba3] Correlation ID: 590cac7, Job ID:
a66974f1-d8f1-477d-85cc-51c3ef70ebdb, Call Stack: null, Custom Event ID:
-1, Message: VM myvm05 started on Host h1<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4139">
2017-06-17 10:15:14,881 INFO
[org.ovirt.engine.core.sso.servlets.OAuthRevokeServlet] (default
task-62) [] User admin@internal successfully logged out<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4140">
2017-06-17 10:15:14,889 INFO
[org.ovirt.engine.core.bll.aaa.TerminateSessionsForTokenCommand]
(default task-46) [361ecd2f] Running command:
TerminateSessionsForTokenCommand internal: true.<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4141">
2017-06-17 10:15:24,749 INFO
[org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
(DefaultQuartzScheduler4) [1a9e3b34] Setting new tasks map. The map
contains now 0 tasks<br id="yiv7471100670yui_3_16_0_ym19_1_1497684224227_4142">
2017-06-17 10:15:24,749 INFO
[org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
(DefaultQuartzScheduler4) [1a9e3b34] Cleared all tasks of pool
'ca3d926a-fff6-41e3-bddd-f244289713fc'.</div></div></div></div></div></div></div></body></html>