3:29 a.m.
------=_Part_226735_346808180.1497684594828
Content-Type: text/plain; charset=UTF-8
Content-Transfer-Encoding: quoted-printable
Dear Martin
My Ovirt Engine Log In The Time When Ansible Script Run Can You Please chec=
k the log :
2017-06-17 10:13:53,478 INFO=C2=A0 [org.ovirt.engine.core.sso.utils.Authent=
icationUtils] (default task-5) [] User admin@internal successfully logged i=
n with scopes: ovirt-app-api ovirt-ext=3Dtoken-info:authz-search ovirt-ext=
=3Dtoken-info:public-authz-search ovirt-ext=3Dtoken-info:validate
2017-06-17 10:13:54,076 INFO=C2=A0 [org.ovirt.engine.core.bll.aaa.CreateUse=
rSessionCommand] (default task-2) [2cefa073] Running command: CreateUserSes=
sionCommand internal: false.
2017-06-17 10:13:54,227 INFO=C2=A0 [org.ovirt.engine.core.bll.AddVmCommand]=
(default task-1) [22a2647d] Lock Acquired to object 'EngineLock:{exclusive=
Locks=3D'[myvm05=3D<VM_NAME, ACTION_TYPE_FAILED_OBJECT_LOCKED>]',
sharedLoc=
ks=3D'[71e0d46a-d8b8-48dc-bae4-3895a42fa005=3D<TEMPLATE, ACTION_TYPE_FAILED=
_TEMPLATE_IS_USED_FOR_CREATE_VM$VmName myvm05>, 4aba3e03-6665-46d5-882c-8d8=
931a563f0=3D<DISK, ACTION_TYPE_FAILED_DISK_IS_USED_FOR_CREATE_VM$VmName myv=
m05>]'}'
2017-06-17 10:13:54,287 INFO=C2=A0 [org.ovirt.engine.core.bll.AddVmCommand]=
(default task-1) [22a2647d] Running command: AddVmCommand internal: false.=
Entities affected :=C2=A0 ID: ffc26dac-0aeb-4486-8ce4-6eada0a99f0a Type: C=
lusterAction group CREATE_VM with role type USER,=C2=A0 ID: 71e0d46a-d8b8-4=
8dc-bae4-3895a42fa005 Type: VmTemplateAction group CREATE_VM with role type=
USER,=C2=A0 ID: 4a62e1c2-8942-48f6-a3c4-1e54d158e13a Type: StorageAction g=
roup CREATE_DISK with role type USER
2017-06-17 10:13:54,362 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.SetVmSt=
atusVDSCommand] (default task-1) [] START, SetVmStatusVDSCommand( SetVmStat=
usVDSCommandParameters:{runAsync=3D'true', vmId=3D'535f7d2b-a3df-4f7e-91e1-=
7b564bff625c', status=3D'ImageLocked', exitStatus=3D'Normal'}), log
id: 758=
c11b1
2017-06-17 10:13:54,366 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.SetVmSt=
atusVDSCommand] (default task-1) [] FINISH, SetVmStatusVDSCommand, log id: =
758c11b1
2017-06-17 10:13:54,375 INFO=C2=A0 [org.ovirt.engine.core.bll.snapshots.Cre=
ateSnapshotFromTemplateCommand] (default task-1) [431bbdcb] Running command=
: CreateSnapshotFromTemplateCommand internal: true. Entities affected :=C2=
=A0 ID: 4a62e1c2-8942-48f6-a3c4-1e54d158e13a Type: Storage
2017-06-17 10:13:54,389 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.irsbrok=
er.CreateSnapshotVDSCommand] (default task-1) [431bbdcb] START, CreateSnaps=
hotVDSCommand( CreateSnapshotVDSCommandParameters:{runAsync=3D'true', stora=
gePoolId=3D'ca3d926a-fff6-41e3-bddd-f244289713fc', ignoreFailoverLimit=3D'f=
alse', storageDomainId=3D'4a62e1c2-8942-48f6-a3c4-1e54d158e13a', imageGroup=
Id=3D'67bfd3f8-8def-4b3a-bb45-c52aed5d1a4f', imageSizeInBytes=3D'1717986918=
4', volumeFormat=3D'COW',
newImageId=3D'b5142477-2134-470e-8ba4-a2e72119ec9=
a', newImageDescription=3D'', imageInitialSizeInBytes=3D'0',
imageId=3D'901=
367d7-8a2a-4f7e-80e8-636738ea3990', sourceImageGroupId=3D'4aba3e03-6665-46d=
5-882c-8d8931a563f0'}), log id: 1931032
2017-06-17 10:13:54,390 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.irsbrok=
er.CreateSnapshotVDSCommand] (default task-1) [431bbdcb] -- executeIrsBroke=
rCommand: calling 'createVolume' with two new parameters: description and U=
UID
2017-06-17 10:13:55,518 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.irsbrok=
er.CreateSnapshotVDSCommand] (default task-1) [431bbdcb] FINISH, CreateSnap=
shotVDSCommand, return: b5142477-2134-470e-8ba4-a2e72119ec9a, log id: 19310=
32
2017-06-17 10:13:55,521 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.Command=
AsyncTask] (default task-1) [431bbdcb] CommandAsyncTask::Adding CommandMult=
iAsyncTasks object for command 'b35f7ba3-89fc-4720-86e2-98cfa2ac4aeb'
2017-06-17 10:13:55,521 INFO=C2=A0 [org.ovirt.engine.core.bll.CommandMultiA=
syncTasks] (default task-1) [431bbdcb] CommandMultiAsyncTasks::attachTask: =
Attaching task 'a236b425-4414-49d8-860b-a0e932db175f' to command 'b35f7ba3-=
89fc-4720-86e2-98cfa2ac4aeb'.
2017-06-17 10:13:55,529 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.AsyncTa=
skManager] (default task-1) [431bbdcb] Adding task 'a236b425-4414-49d8-860b=
-a0e932db175f' (Parent Command 'CreateSnapshotFromTemplate', Parameters Typ=
e 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling h=
asn't started yet..
2017-06-17 10:13:55,571 INFO=C2=A0 [org.ovirt.engine.core.dal.dbbroker.audi=
tloghandling.AuditLogDirector] (default task-1) [431bbdcb] Correlation ID: =
22a2647d, Job ID: 638b7524-baaa-4bb2-bb36-e02b78bb5dfd, Call Stack: null, C=
ustom Event ID: -1, Message: VM myvm05 creation was initiated by admin@inte=
rnal-authz.
2017-06-17 10:13:55,571 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.SPMAsyn=
cTask] (default task-1) [431bbdcb] BaseAsyncTask::startPollingTask: Startin=
g to poll task 'a236b425-4414-49d8-860b-a0e932db175f'.
2017-06-17 10:13:57,274 INFO=C2=A0 [org.ovirt.engine.core.bll.ConcurrentChi=
ldCommandsExecutionCallback] (DefaultQuartzScheduler5) [431bbdcb] Command '=
AddVm' (id: 'd089dd43-f977-4eb7-aa22-245d8ab69e5d') waiting on child comman=
d id: 'b35f7ba3-89fc-4720-86e2-98cfa2ac4aeb' type:'CreateSnapshotFromTempla=
te' to complete
2017-06-17 10:13:58,289 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.AsyncTa=
skManager] (DefaultQuartzScheduler8) [3a68eba3] Polling and updating Async =
Tasks: 2 tasks, 1 tasks to poll now
2017-06-17 10:13:58,586 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.SPMAsyn=
cTask] (DefaultQuartzScheduler8) [3a68eba3] SPMAsyncTask::PollTask: Polling=
task 'a236b425-4414-49d8-860b-a0e932db175f' (Parent Command 'CreateSnapsho=
tFromTemplate', Parameters Type 'org.ovirt.engine.core.common.asynctasks.As=
yncTaskParameters') returned status 'finished', result 'success'.
2017-06-17 10:13:58,589 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.SPMAsyn=
cTask] (DefaultQuartzScheduler8) [3a68eba3] BaseAsyncTask::onTaskEndSuccess=
: Task 'a236b425-4414-49d8-860b-a0e932db175f' (Parent Command 'CreateSnapsh=
otFromTemplate', Parameters Type 'org.ovirt.engine.core.common.asynctasks.A=
syncTaskParameters') ended successfully.
2017-06-17 10:13:58,591 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.Command=
AsyncTask] (DefaultQuartzScheduler8) [3a68eba3] CommandAsyncTask::endAction=
IfNecessary: All tasks of command 'b35f7ba3-89fc-4720-86e2-98cfa2ac4aeb' ha=
s ended -> executing 'endAction'
2017-06-17 10:13:58,591 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.Command=
AsyncTask] (DefaultQuartzScheduler8) [3a68eba3] CommandAsyncTask::endAction=
: Ending action for '1' tasks (command ID: 'b35f7ba3-89fc-4720-86e2-98cfa2a=
c4aeb'): calling endAction '.
2017-06-17 10:13:58,591 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.Command=
AsyncTask] (org.ovirt.thread.pool-6-thread-38) [3a68eba3] CommandAsyncTask:=
:endCommandAction [within thread] context: Attempting to endAction 'CreateS=
napshotFromTemplate', executionIndex: '0'
2017-06-17 10:13:58,596 INFO=C2=A0 [org.ovirt.engine.core.bll.snapshots.Cre=
ateSnapshotFromTemplateCommand] (org.ovirt.thread.pool-6-thread-38) [431bbd=
cb] Command [id=3Db35f7ba3-89fc-4720-86e2-98cfa2ac4aeb]: Updating status to=
'SUCCEEDED', The command end method logic will be executed by one of its p=
arent commands.
2017-06-17 10:13:58,597 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.Command=
AsyncTask] (org.ovirt.thread.pool-6-thread-38) [431bbdcb] CommandAsyncTask:=
:HandleEndActionResult [within thread]: endAction for action type 'CreateSn=
apshotFromTemplate' completed, handling the result.
2017-06-17 10:13:58,597 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.Command=
AsyncTask] (org.ovirt.thread.pool-6-thread-38) [431bbdcb] CommandAsyncTask:=
:HandleEndActionResult [within thread]: endAction for action type 'CreateSn=
apshotFromTemplate' succeeded, clearing tasks.
2017-06-17 10:13:58,597 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.SPMAsyn=
cTask] (org.ovirt.thread.pool-6-thread-38) [431bbdcb] SPMAsyncTask::ClearAs=
yncTask: Attempting to clear task 'a236b425-4414-49d8-860b-a0e932db175f'
2017-06-17 10:13:58,598 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.irsbrok=
er.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-38) [431bbdcb] S=
TART, SPMClearTaskVDSCommand( SPMTaskGuidBaseVDSCommandParameters:{runAsync=
=3D'true', storagePoolId=3D'ca3d926a-fff6-41e3-bddd-f244289713fc',
ignoreFa=
iloverLimit=3D'false', taskId=3D'a236b425-4414-49d8-860b-a0e932db175f'}),
l=
og id: 37a1669b
2017-06-17 10:13:58,598 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.vdsbrok=
er.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-38) [431bbdcb] S=
TART, HSMClearTaskVDSCommand(HostName =3D h1, HSMTaskGuidBaseVDSCommandPara=
meters:{runAsync=3D'true',
hostId=3D'e62505b1-d9b6-4231-992c-8e4851c66624',=
taskId=3D'a236b425-4414-49d8-860b-a0e932db175f'}), log id: 5ecec77e
2017-06-17 10:13:59,633 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.vdsbrok=
er.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-38) [431bbdcb] F=
INISH, HSMClearTaskVDSCommand, log id: 5ecec77e
2017-06-17 10:13:59,633 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.irsbrok=
er.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-38) [431bbdcb] F=
INISH, SPMClearTaskVDSCommand, log id: 37a1669b
2017-06-17 10:13:59,635 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.SPMAsyn=
cTask] (org.ovirt.thread.pool-6-thread-38) [431bbdcb] BaseAsyncTask::remove=
TaskFromDB: Removed task 'a236b425-4414-49d8-860b-a0e932db175f' from DataBa=
se
2017-06-17 10:13:59,635 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.Command=
AsyncTask] (org.ovirt.thread.pool-6-thread-38) [431bbdcb] CommandAsyncTask:=
:HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks obj=
ect for entity 'b35f7ba3-89fc-4720-86e2-98cfa2ac4aeb'
2017-06-17 10:14:01,284 INFO=C2=A0 [org.ovirt.engine.core.bll.ConcurrentChi=
ldCommandsExecutionCallback] (DefaultQuartzScheduler7) [431bbdcb] Command '=
AddVm' id: 'd089dd43-f977-4eb7-aa22-245d8ab69e5d' child commands
'[b35f7ba3=
-89fc-4720-86e2-98cfa2ac4aeb]' executions were completed, status 'SUCCEEDED=
'
2017-06-17 10:14:02,299 INFO=C2=A0 [org.ovirt.engine.core.bll.AddVmCommand]=
(DefaultQuartzScheduler2) [431bbdcb] Ending command 'org.ovirt.engine.core=
.bll.AddVmCommand' successfully.
2017-06-17 10:14:02,304 INFO=C2=A0 [org.ovirt.engine.core.bll.snapshots.Cre=
ateSnapshotFromTemplateCommand] (DefaultQuartzScheduler2) [431bbdcb] Ending=
command 'org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCom=
mand' successfully.
2017-06-17 10:14:02,309 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.irsbrok=
er.GetImageInfoVDSCommand] (DefaultQuartzScheduler2) [431bbdcb] START, GetI=
mageInfoVDSCommand( GetImageInfoVDSCommandParameters:{runAsync=3D'true', st=
oragePoolId=3D'ca3d926a-fff6-41e3-bddd-f244289713fc', ignoreFailoverLimit=
=3D'false', storageDomainId=3D'4a62e1c2-8942-48f6-a3c4-1e54d158e13a',
image=
GroupId=3D'67bfd3f8-8def-4b3a-bb45-c52aed5d1a4f', imageId=3D'b5142477-2134-=
470e-8ba4-a2e72119ec9a'}), log id: 6fd9785f
2017-06-17 10:14:03,344 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.irsbrok=
er.GetImageInfoVDSCommand] (DefaultQuartzScheduler2) [431bbdcb] FINISH, Get=
ImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.=
storage.DiskImage@22deef7c, log id: 6fd9785f
2017-06-17 10:14:03,356 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.SetVmSt=
atusVDSCommand] (DefaultQuartzScheduler2) [] START, SetVmStatusVDSCommand( =
SetVmStatusVDSCommandParameters:{runAsync=3D'true', vmId=3D'535f7d2b-a3df-4=
f7e-91e1-7b564bff625c', status=3D'Down', exitStatus=3D'Normal'}), log
id: 4=
4ed9ea6
2017-06-17 10:14:03,358 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.SetVmSt=
atusVDSCommand] (DefaultQuartzScheduler2) [] FINISH, SetVmStatusVDSCommand,=
log id: 44ed9ea6
2017-06-17 10:14:03,362 INFO=C2=A0 [org.ovirt.engine.core.bll.AddVmCommand]=
(DefaultQuartzScheduler2) [] Lock freed to object 'EngineLock:{exclusiveLo=
cks=3D'[myvm05=3D<VM_NAME, ACTION_TYPE_FAILED_OBJECT_LOCKED>]',
sharedLocks=
=3D'[71e0d46a-d8b8-48dc-bae4-3895a42fa005=3D<TEMPLATE, ACTION_TYPE_FAILED_T=
EMPLATE_IS_USED_FOR_CREATE_VM$VmName myvm05>, 4aba3e03-6665-46d5-882c-8d893=
1a563f0=3D<DISK, ACTION_TYPE_FAILED_DISK_IS_USED_FOR_CREATE_VM$VmName myvm0=
5>]'}'
2017-06-17 10:14:03,370 INFO=C2=A0 [org.ovirt.engine.core.dal.dbbroker.audi=
tloghandling.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.
2017-06-17 10:14:04,768 INFO=C2=A0 [org.ovirt.engine.core.bll.RunVmOnceComm=
and] (default task-19) [590cac7] Lock Acquired to object 'EngineLock:{exclu=
siveLocks=3D'[535f7d2b-a3df-4f7e-91e1-7b564bff625c=3D<VM, ACTION_TYPE_FAILE=
D_OBJECT_LOCKED>]', sharedLocks=3D'null'}'
2017-06-17 10:14:04,777 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.IsVmDur=
ingInitiatingVDSCommand] (default task-19) [590cac7] START, IsVmDuringIniti=
atingVDSCommand( IsVmDuringInitiatingVDSCommandParameters:{runAsync=3D'true=
', vmId=3D'535f7d2b-a3df-4f7e-91e1-7b564bff625c'}), log id: 261674f1
2017-06-17 10:14:04,777 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.IsVmDur=
ingInitiatingVDSCommand] (default task-19) [590cac7] FINISH, IsVmDuringInit=
iatingVDSCommand, return: false, log id: 261674f1
2017-06-17 10:14:04,798 INFO=C2=A0 [org.ovirt.engine.core.bll.RunVmOnceComm=
and] (default task-19) [590cac7] Running command: RunVmOnceCommand internal=
: false. Entities affected :=C2=A0 ID: 535f7d2b-a3df-4f7e-91e1-7b564bff625c=
Type: VMAction group RUN_VM with role type USER
2017-06-17 10:14:04,817 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.UpdateV=
mDynamicDataVDSCommand] (default task-19) [590cac7] START, UpdateVmDynamicD=
ataVDSCommand( UpdateVmDynamicDataVDSCommandParameters:{runAsync=3D'true', =
hostId=3D'null', vmId=3D'00000000-0000-0000-0000-000000000000',
vmDynamic=
=3D'org.ovirt.engine.core.common.businessentities.VmDynamic@e1224d5b'}), lo=
g id: bc72f78
2017-06-17 10:14:04,820 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.UpdateV=
mDynamicDataVDSCommand] (default task-19) [590cac7] FINISH, UpdateVmDynamic=
DataVDSCommand, log id: bc72f78
2017-06-17 10:14:04,822 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.CreateV=
mVDSCommand] (default task-19) [590cac7] START, CreateVmVDSCommand( CreateV=
mVDSCommandParameters:{runAsync=3D'true', hostId=3D'e62505b1-d9b6-4231-992c=
-8e4851c66624', vmId=3D'535f7d2b-a3df-4f7e-91e1-7b564bff625c', vm=3D'VM
[my=
vm05]'}), log id: 5d92bbd3
2017-06-17 10:14:04,825 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.vdsbrok=
er.CreateVmFromCloudInitVDSCommand] (default task-19) [590cac7] START, Crea=
teVmFromCloudInitVDSCommand(HostName =3D h1, CreateVmVDSCommandParameters:{=
runAsync=3D'true', hostId=3D'e62505b1-d9b6-4231-992c-8e4851c66624',
vmId=3D=
'535f7d2b-a3df-4f7e-91e1-7b564bff625c', vm=3D'VM [myvm05]'}), log id:
7b28f=
107
2017-06-17 10:14:04,828 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.vdsbrok=
er.VmInfoBuilderBase] (default task-19) [590cac7] Bootable disk '67bfd3f8-8=
def-4b3a-bb45-c52aed5d1a4f' set to index '0'
2017-06-17 10:14:04,842 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.vdsbrok=
er.CreateVDSCommand] (default task-19) [590cac7] org.ovirt.engine.core.vdsb=
roker.vdsbroker.CreateVmFromCloudInitVDSCommand pitReinjection=3Dfalse,memG=
uaranteedSize=3D2000,smpThreadsPerCore=3D1,cpuType=3DWestmere,vmId=3D535f7d=
2b-a3df-4f7e-91e1-7b564bff625c,acpiEnable=3Dtrue,vmType=3Dkvm,smp=3D8,smpCo=
resPerSocket=3D2,emulatedMachine=3Dpc-i440fx-rhel7.2.0,smartcardEnable=3Dfa=
lse,guestNumaNodes=3D[{memory=3D4096, cpus=3D0,1,2,3,4,5,6,7, nodeIndex=3D0=
}],transparentHugePages=3Dtrue,displayNetwork=3Dovirtmgmt,vmName=3Dmyvm05,m=
axVCpus=3D32,kvmEnable=3Dtrue,devices=3D[{iface=3Dide, shared=3Dfalse, path=
=3D, readonly=3Dtrue, index=3D3, type=3Ddisk, specParams=3D{vmPayload=3D{fi=
le=3D{openstack/latest/meta_data.json=3DewogICJuZXR3b3JrLWludGVyZmFjZXMiIDo=
gImF1dG8gZXRoMFxuaWZhY2UgZXRoMCBpbmV0IHN0YXRpY1xuICBhZGRyZXNzIDEwLjEwLjEwLj=
UwXG4gIG5ldG1hc2sgMjU1LjI1NS4yNTUuMFxuICBnYXRld2F5IDEwLjEwLjEwLjFcbiAgZG5zL=
W5hbWVzZXJ2ZXJzIDEwOS4yMjQuMTQuMlxuICBkbnMtc2VhcmNoIGVsY2xkLm5ldFxuIiwKICAi=
YXZhaWxhYmlsaXR5X3pvbmUiIDogIm5vdmEiLAogICJob3N0bmFtZSIgOiAidm0wMSIsCiAgImx=
hdW5jaF9pbmRleCIgOiAiMCIsCiAgIm1ldGEiIDogewogICAgInJvbGUiIDogInNlcnZlciIsCi=
AgICAiZHNtb2RlIiA6ICJsb2NhbCIsCiAgICAiZXNzZW50aWFsIiA6ICJmYWxzZSIKICB9LAogI=
CJuYW1lIiA6ICJ2bTAxIiwKICAibmV0d29ya19jb25maWciIDogewogICAgInBhdGgiIDogIi9l=
dGMvbmV0d29yay9pbnRlcmZhY2VzIiwKICAgICJjb250ZW50X3BhdGgiIDogIi9jb250ZW50LzA=
wMDAiCiAgfSwKICAidXVpZCIgOiAiNjdjOThkOWQtOWZlOC00ZWU2LTg0NGUtZjU4N2Y1Njc5NW=
ExIgp9, openstack/content/0000=3DYXV0byBldGgwCmlmYWNlIGV0aDAgaW5ldCBzdGF0aW=
MKICBhZGRyZXNzIDEwLjEwLjEwLjUwCiAgbmV0bWFzayAyNTUuMjU1LjI1NS4wCiAgZ2F0ZXdhe=
SAxMC4xMC4xMC4xCiAgZG5zLW5hbWVzZXJ2ZXJzIDEwOS4yMjQuMTQuMgogIGRucy1zZWFyY2gg=
ZWxjbGQubmV0Cg=3D=3D, openstack/latest/user_data=3DI2Nsb3VkLWNvbmZpZwpvdXRw=
dXQ6CiAgYWxsOiAnPj4gL3Zhci9sb2cvY2xvdWQtaW5pdC1vdXRwdXQubG9nJwpwYXNzd29yZDo=
gYm9vYm9vCmRpc2FibGVfcm9vdDogMApydW5jbWQ6Ci0gJ3NlZCAtaSAnJy9eZGF0YXNvdXJjZV=
9saXN0OiAvZCcnIC9ldGMvY2xvdWQvY2xvdWQuY2ZnOyBlY2hvICcnZGF0YXNvdXJjZV9saXN0O=
gogIFsiTm9DbG91ZCIsICJDb25maWdEcml2ZSJdJycgPj4gL2V0Yy9jbG91ZC9jbG91ZC5jZmcn=
CnNzaF9wd2F1dGg6IHRydWUKY2hwYXNzd2Q6CiAgZXhwaXJlOiBmYWxzZQp1c2VyOiByb290Cg=
=3D=3D}, volId=3Dconfig-2}}, device=3Dcdrom, deviceId=3D95cee92c-3f8d-4b2b-=
93f9-679c854f84b0}, {type=3Dvideo, specParams=3D{vgamem=3D16384, heads=3D1,=
vram=3D32768, ram=3D65536}, device=3Dqxl, deviceId=3D5cb25f3c-dccb-4a8e-87=
d7-5873db429f4e}, {type=3Dgraphics, specParams=3D{spiceSecureChannels=3Dsma=
in,sinputs,scursor,splayback,srecord,sdisplay,ssmartcard,susbredir, fileTra=
nsferEnable=3Dtrue, spiceSslCipherSuite=3DDEFAULT, copyPasteEnable=3Dtrue},=
device=3Dspice, deviceId=3D4f91a674-25e7-45be-92a6-50285b15d130}, {iface=
=3Dide, shared=3Dfalse, path=3D, address=3D{bus=3D1, controller=3D0, unit=
=3D0, type=3Ddrive, target=3D0}, readonly=3Dtrue, index=3D2, type=3Ddisk, s=
pecParams=3D{path=3D}, device=3Dcdrom, deviceId=3Dabc8d3e6-ef97-4e2a-9559-e=
e62519a8e81}, {shared=3Dfalse, address=3D{bus=3D0x00, domain=3D0x0000, func=
tion=3D0x0, slot=3D0x06, type=3Dpci}, imageID=3D67bfd3f8-8def-4b3a-bb45-c52=
aed5d1a4f, format=3Dcow, index=3D0, optional=3Dfalse, type=3Ddisk, deviceId=
=3D67bfd3f8-8def-4b3a-bb45-c52aed5d1a4f, domainID=3D4a62e1c2-8942-48f6-a3c4=
-1e54d158e13a, propagateErrors=3Doff, iface=3Dvirtio, readonly=3Dfalse, boo=
tOrder=3D1, poolID=3Dca3d926a-fff6-41e3-bddd-f244289713fc, volumeID=3Db5142=
477-2134-470e-8ba4-a2e72119ec9a, specParams=3D{}, device=3Ddisk}, {filter=
=3Dvdsm-no-mac-spoofing, nicModel=3Dpv, address=3D{bus=3D0x00, domain=3D0x0=
000, function=3D0x0, slot=3D0x03, type=3Dpci}, type=3Dinterface, specParams=
=3D{inbound=3D{}, outbound=3D{}}, device=3Dbridge, linkActive=3Dtrue, devic=
eId=3Dd90c311d-1d07-445d-bc0c-93da4fc38327, macAddr=3D00:1a:4a:16:01:69, ne=
twork=3Dovirtmgmt}, {address=3D{bus=3D0x00, function=3D0x0, domain=3D0x0000=
, slot=3D0x07, type=3Dpci}, type=3Dballoon, specParams=3D{model=3Dvirtio}, =
device=3Dmemballoon, deviceId=3Ddcf2375e-54b2-471f-9dba-a0c7d509492f}, {ind=
ex=3D0, model=3Dvirtio-scsi, type=3Dcontroller, specParams=3D{}, device=3Ds=
csi, deviceId=3De1eb89e1-77df-4677-ba9a-4b3c92a46e69}, {address=3D{bus=3D0x=
00, domain=3D0x0000, function=3D0x0, slot=3D0x05, type=3Dpci}, type=3Dcontr=
oller, specParams=3D{}, device=3Dvirtio-serial, deviceId=3D3d096c2f-fdbd-43=
dd-8243-11e562e0a612}],custom=3D{device_966e95b5-6a22-4836-aad0-c9f4bb79389=
e=3DVmDevice:{id=3D'VmDeviceId:{deviceId=3D'966e95b5-6a22-4836-aad0-c9f4bb7=
9389e', vmId=3D'535f7d2b-a3df-4f7e-91e1-7b564bff625c'}',
device=3D'unix', t=
ype=3D'CHANNEL', bootOrder=3D'0', specParams=3D'[]',
address=3D'{bus=3D0, c=
ontroller=3D0, type=3Dvirtio-serial, port=3D1}', managed=3D'false', plugged=
=3D'true', readOnly=3D'false', deviceAlias=3D'channel0',
customProperties=
=3D'[]', snapshotId=3D'null', logicalName=3D'null',
usingScsiReservation=3D=
'false', hostDevice=3D'null'},
device_966e95b5-6a22-4836-aad0-c9f4bb79389ed=
evice_5172a707-33ea-4f05-855b-40232655ccb6=3DVmDevice:{id=3D'VmDeviceId:{de=
viceId=3D'5172a707-33ea-4f05-855b-40232655ccb6', vmId=3D'535f7d2b-a3df-4f7e=
-91e1-7b564bff625c'}', device=3D'spicevmc', type=3D'CHANNEL',
bootOrder=3D'=
0', specParams=3D'[]', address=3D'{bus=3D0, controller=3D0,
type=3Dvirtio-s=
erial, port=3D3}', managed=3D'false', plugged=3D'true',
readOnly=3D'false',=
deviceAlias=3D'channel2', customProperties=3D'[]',
snapshotId=3D'null', lo=
gicalName=3D'null', usingScsiReservation=3D'false',
hostDevice=3D'null'}, d=
evice_966e95b5-6a22-4836-aad0-c9f4bb79389edevice_5172a707-33ea-4f05-855b-40=
232655ccb6device_60409373-848b-43e5-8c8c-117688b0ba71=3DVmDevice:{id=3D'VmD=
eviceId:{deviceId=3D'60409373-848b-43e5-8c8c-117688b0ba71', vmId=3D'535f7d2=
b-a3df-4f7e-91e1-7b564bff625c'}', device=3D'ide',
type=3D'CONTROLLER', boot=
Order=3D'0', specParams=3D'[]', address=3D'{slot=3D0x01, bus=3D0x00,
domain=
=3D0x0000, type=3Dpci, function=3D0x1}', managed=3D'false',
plugged=3D'true=
', readOnly=3D'false', deviceAlias=3D'ide',
customProperties=3D'[]', snapsh=
otId=3D'null', logicalName=3D'null',
usingScsiReservation=3D'false', hostDe=
vice=3D'null'}, device_966e95b5-6a22-4836-aad0-c9f4bb79389edevice_5172a707-=
33ea-4f05-855b-40232655ccb6device_60409373-848b-43e5-8c8c-117688b0ba71devic=
e_63038946-00aa-4b0a-9045-5dfb47b31e89=3DVmDevice:{id=3D'VmDeviceId:{device=
Id=3D'63038946-00aa-4b0a-9045-5dfb47b31e89', vmId=3D'535f7d2b-a3df-4f7e-91e=
1-7b564bff625c'}', device=3D'unix', type=3D'CHANNEL',
bootOrder=3D'0', spec=
Params=3D'[]', address=3D'{bus=3D0, controller=3D0, type=3Dvirtio-serial, p=
ort=3D2}', managed=3D'false', plugged=3D'true',
readOnly=3D'false', deviceA=
lias=3D'channel1', customProperties=3D'[]', snapshotId=3D'null',
logicalNam=
e=3D'null', usingScsiReservation=3D'false',
hostDevice=3D'null'}},display=
=3Dqxl,timeOffset=3D0,nice=3D0,maxMemSize=3D4194304,maxMemSlots=3D16,bootMe=
nuEnable=3Dfalse,memSize=3D4096
2017-06-17 10:14:04,856 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.vdsbrok=
er.CreateVmFromCloudInitVDSCommand] (default task-19) [590cac7] FINISH, Cre=
ateVmFromCloudInitVDSCommand, log id: 7b28f107
2017-06-17 10:14:04,860 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.CreateV=
mVDSCommand] (default task-19) [590cac7] FINISH, CreateVmVDSCommand, return=
: WaitForLaunch, log id: 5d92bbd3
2017-06-17 10:14:04,860 INFO=C2=A0 [org.ovirt.engine.core.bll.RunVmOnceComm=
and] (default task-19) [590cac7] Lock freed to object 'EngineLock:{exclusiv=
eLocks=3D'[535f7d2b-a3df-4f7e-91e1-7b564bff625c=3D<VM, ACTION_TYPE_FAILED_O=
BJECT_LOCKED>]', sharedLocks=3D'null'}'
2017-06-17 10:14:04,863 INFO=C2=A0 [org.ovirt.engine.core.dal.dbbroker.audi=
tloghandling.AuditLogDirector] (default task-19) [590cac7] Correlation ID: =
590cac7, Job ID: a66974f1-d8f1-477d-85cc-51c3ef70ebdb, Call Stack: null, Cu=
stom Event ID: -1, Message: VM myvm05 was started by admin@internal-authz (=
Host: h1).
2017-06-17 10:14:06,360 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.monitor=
ing.VmAnalyzer] (ForkJoinPool-1-worker-1) [] VM '535f7d2b-a3df-4f7e-91e1-7b=
564bff625c'(myvm05) moved from 'WaitForLaunch' --> 'PoweringUp'
2017-06-17 10:14:06,367 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.vdsbrok=
er.FullListVDSCommand] (ForkJoinPool-1-worker-1) [] START, FullListVDSComma=
nd(HostName =3D , FullListVDSCommandParameters:{runAsync=3D'true', hostId=
=3D'e62505b1-d9b6-4231-992c-8e4851c66624', vds=3D'Host[,e62505b1-d9b6-4231-=
992c-8e4851c66624]', vmIds=3D'[535f7d2b-a3df-4f7e-91e1-7b564bff625c]'}), lo=
g id: 3a801479
2017-06-17 10:14:06,376 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.vdsbrok=
er.FullListVDSCommand] (ForkJoinPool-1-worker-1) [] FINISH, FullListVDSComm=
and, return: [{acpiEnable=3Dtrue, emulatedMachine=3Dpc-i440fx-rhel7.2.0, vm=
Id=3D535f7d2b-a3df-4f7e-91e1-7b564bff625c, guestDiskMapping=3D{}, transpare=
ntHugePages=3Dtrue, timeOffset=3D0, cpuType=3DWestmere, smp=3D8, pauseCode=
=3DNOERR, guestNumaNodes=3D[Ljava.lang.Object;@8a07465, smartcardEnable=3Df=
alse, custom=3D{device_966e95b5-6a22-4836-aad0-c9f4bb79389edevice_5172a707-=
33ea-4f05-855b-40232655ccb6device_60409373-848b-43e5-8c8c-117688b0ba71=3DVm=
Device:{id=3D'VmDeviceId:{deviceId=3D'60409373-848b-43e5-8c8c-117688b0ba71'=
, vmId=3D'535f7d2b-a3df-4f7e-91e1-7b564bff625c'}', device=3D'ide',
type=3D'=
CONTROLLER', bootOrder=3D'0', specParams=3D'[]',
address=3D'{slot=3D0x01, b=
us=3D0x00, domain=3D0x0000, type=3Dpci, function=3D0x1}', managed=3D'false'=
, plugged=3D'true', readOnly=3D'false', deviceAlias=3D'ide',
customProperti=
es=3D'[]', snapshotId=3D'null', logicalName=3D'null',
usingScsiReservation=
=3D'false', hostDevice=3D'null'},
device_966e95b5-6a22-4836-aad0-c9f4bb7938=
9edevice_5172a707-33ea-4f05-855b-40232655ccb6=3DVmDevice:{id=3D'VmDeviceId:=
{deviceId=3D'5172a707-33ea-4f05-855b-40232655ccb6', vmId=3D'535f7d2b-a3df-4=
f7e-91e1-7b564bff625c'}', device=3D'spicevmc', type=3D'CHANNEL',
bootOrder=
=3D'0', specParams=3D'[]', address=3D'{bus=3D0, controller=3D0,
type=3Dvirt=
io-serial, port=3D3}', managed=3D'false', plugged=3D'true',
readOnly=3D'fal=
se', deviceAlias=3D'channel2', customProperties=3D'[]',
snapshotId=3D'null'=
, logicalName=3D'null', usingScsiReservation=3D'false',
hostDevice=3D'null'=
}, device_966e95b5-6a22-4836-aad0-c9f4bb79389edevice_5172a707-33ea-4f05-855=
b-40232655ccb6device_60409373-848b-43e5-8c8c-117688b0ba71device_63038946-00=
aa-4b0a-9045-5dfb47b31e89=3DVmDevice:{id=3D'VmDeviceId:{deviceId=3D'6303894=
6-00aa-4b0a-9045-5dfb47b31e89', vmId=3D'535f7d2b-a3df-4f7e-91e1-7b564bff625=
c'}', device=3D'unix', type=3D'CHANNEL', bootOrder=3D'0',
specParams=3D'[]'=
, address=3D'{bus=3D0, controller=3D0, type=3Dvirtio-serial, port=3D2}', ma=
naged=3D'false', plugged=3D'true', readOnly=3D'false',
deviceAlias=3D'chann=
el1', customProperties=3D'[]', snapshotId=3D'null',
logicalName=3D'null', u=
singScsiReservation=3D'false', hostDevice=3D'null'},
device_966e95b5-6a22-4=
836-aad0-c9f4bb79389e=3DVmDevice:{id=3D'VmDeviceId:{deviceId=3D'966e95b5-6a=
22-4836-aad0-c9f4bb79389e',
vmId=3D'535f7d2b-a3df-4f7e-91e1-7b564bff625c'}'=
, device=3D'unix', type=3D'CHANNEL', bootOrder=3D'0',
specParams=3D'[]', ad=
dress=3D'{bus=3D0, controller=3D0, type=3Dvirtio-serial, port=3D1}', manage=
d=3D'false', plugged=3D'true', readOnly=3D'false',
deviceAlias=3D'channel0'=
, customProperties=3D'[]', snapshotId=3D'null',
logicalName=3D'null', using=
ScsiReservation=3D'false', hostDevice=3D'null'}}, vmType=3Dkvm,
memSize=3D4=
096, smpCoresPerSocket=3D2, vmName=3Dmyvm05, nice=3D0, status=3DUp, maxMemS=
ize=3D4194304, bootMenuEnable=3Dfalse, pid=3D1569, smpThreadsPerCore=3D1, m=
emGuaranteedSize=3D2000, kvmEnable=3Dtrue, pitReinjection=3Dfalse, displayN=
etwork=3Dovirtmgmt, devices=3D[Ljava.lang.Object;@690fc889, display=3Dqxl, =
maxVCpus=3D32, clientIp=3D, statusTime=3D4467062130, maxMemSlots=3D16}], lo=
g id: 3a801479
2017-06-17 10:14:06,379 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.monitor=
ing.VmDevicesMonitoring] (ForkJoinPool-1-worker-1) [] Received a spice Devi=
ce without an address when processing VM 535f7d2b-a3df-4f7e-91e1-7b564bff62=
5c devices, skipping device: {device=3Dspice, specParams=3D{fileTransferEna=
ble=3Dtrue, displayNetwork=3Dovirtmgmt, displayIp=3D192.168.215.215, spiceS=
slCipherSuite=3DDEFAULT, spiceSecureChannels=3Dsmain,sinputs,scursor,splayb=
ack,srecord,sdisplay,ssmartcard,susbredir, copyPasteEnable=3Dtrue}, type=3D=
graphics, deviceId=3D4f91a674-25e7-45be-92a6-50285b15d130, tlsPort=3D5901}
2017-06-17 10:14:13,332 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.monitor=
ing.VmsStatisticsFetcher] (DefaultQuartzScheduler6) [3465d470] Fetched 2 VM=
s from VDS 'e62505b1-d9b6-4231-992c-8e4851c66624'
2017-06-17 10:14:54,749 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.AsyncTa=
skManager] (DefaultQuartzScheduler4) [1a9e3b34] Setting new tasks map. The =
map contains now 1 tasks
2017-06-17 10:15:13,767 INFO=C2=A0 [org.ovirt.engine.core.vdsbroker.monitor=
ing.VmAnalyzer] (DefaultQuartzScheduler9) [3a68eba3] VM '535f7d2b-a3df-4f7e=
-91e1-7b564bff625c'(myvm05) moved from 'PoweringUp' --> 'Up'
2017-06-17 10:15:13,780 INFO=C2=A0 [org.ovirt.engine.core.dal.dbbroker.audi=
tloghandling.AuditLogDirector] (DefaultQuartzScheduler9) [3a68eba3] Correla=
tion ID: 590cac7, Job ID: a66974f1-d8f1-477d-85cc-51c3ef70ebdb, Call Stack:=
null, Custom Event ID: -1, Message: VM myvm05 started on Host h1
2017-06-17 10:15:14,881 INFO=C2=A0 [org.ovirt.engine.core.sso.servlets.OAut=
hRevokeServlet] (default task-62) [] User admin@internal successfully logge=
d out
2017-06-17 10:15:14,889 INFO=C2=A0 [org.ovirt.engine.core.bll.aaa.Terminate=
SessionsForTokenCommand] (default task-46) [361ecd2f] Running command: Term=
inateSessionsForTokenCommand internal: true.
2017-06-17 10:15:24,749 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.AsyncTa=
skManager] (DefaultQuartzScheduler4) [1a9e3b34] Setting new tasks map. The =
map contains now 0 tasks
2017-06-17 10:15:24,749 INFO=C2=A0 [org.ovirt.engine.core.bll.tasks.AsyncTa=
skManager] (DefaultQuartzScheduler4) [1a9e3b34] Cleared all tasks of pool '=
ca3d926a-fff6-41e3-bddd-f244289713fc'.
------=_Part_226735_346808180.1497684594828
Content-Type: text/html; charset=UTF-8
Content-Transfer-Encoding: 7bit
<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(a)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>
------=_Part_226735_346808180.1497684594828--
4:43 a.m.
Hello,
imho seems everything ok. The VM gets created and powers up. What's
your specific problem? on ansible side which messages you get?
On Sat, Jun 17, 2017 at 9:29 AM, khalid mahmood
<engkhalid21986(a)yahoo.com> wrote:
Dear Martin
My Ovirt Engine Log In The Time When Ansible Script Run Can You Please check
the log :
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
2017-06-17 10:13:54,076 INFO
[org.ovirt.engine.core.bll.aaa.CreateUserSessionCommand] (default task-2)
[2cefa073] Running command: CreateUserSessionCommand internal: false.
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>]'}'
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
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
2017-06-17 10:13:54,366 INFO
[org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (default task-1) []
FINISH, SetVmStatusVDSCommand, log id: 758c11b1
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
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
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
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
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'
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'.
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..
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.
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'.
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
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
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'.
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.
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'
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 '.
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'
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.
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.
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.
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'
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
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
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
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
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
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'
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'
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.
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.
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
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
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
2017-06-17 10:14:03,358 INFO
[org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand]
(DefaultQuartzScheduler2) [] FINISH, SetVmStatusVDSCommand, log id: 44ed9ea6
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>]'}'
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.
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'}'
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
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
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
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
2017-06-17 10:14:04,820 INFO
[org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (default
task-19) [590cac7] FINISH, UpdateVmDynamicDataVDSCommand, log id: bc72f78
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
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
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'
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
2017-06-17 10:14:04,856 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromCloudInitVDSCommand]
(default task-19) [590cac7] FINISH, CreateVmFromCloudInitVDSCommand, log id:
7b28f107
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
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'}'
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).
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'
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
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
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}
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'
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
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'
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
2017-06-17 10:15:14,881 INFO
[org.ovirt.engine.core.sso.servlets.OAuthRevokeServlet] (default task-62) []
User admin@internal successfully logged out
2017-06-17 10:15:14,889 INFO
[org.ovirt.engine.core.bll.aaa.TerminateSessionsForTokenCommand] (default
task-46) [361ecd2f] Running command: TerminateSessionsForTokenCommand
internal: true.
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
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'.
_______________________________________________
Users mailing list
Users(a)ovirt.org
http://lists.ovirt.org/mailman/listinfo/users
--
"E' assurdo impiegare gli uomini di intelligenza eccellente per fare
calcoli che potrebbero essere affidati a chiunque se si usassero delle
macchine"
Gottfried Wilhelm von Leibnitz, Filosofo e Matematico (1646-1716)
"Internet è la più grande biblioteca del mondo.
Ma il problema è che i libri sono tutti sparsi sul pavimento"
John Allen Paulos, Matematico (1945-vivente)
Luca 'remix_tj' Lorenzetto, http://www.remixtj.net ,
<lorenzetto.luca(a)gmail.com>
2746
days inactive
2746
days old
1 comments
2 participants
participants (2)
-
khalid mahmood -
Luca 'remix_tj' Lorenzetto