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
[email protected]
http://lists.ovirt.org/mailman/listinfo/users
