[ovirt-users] Deploy Ovirt VM's By Ansible Playbook Issue

Sat Jun 17 07:29:54 UTC 2017

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 at 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 at 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 at 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 at 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 at 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 at 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'.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ovirt.org/pipermail/users/attachments/20170617/540cd71e/attachment-0001.html>