[ovirt-users] Pool with "latest template" does not sysprep when rebased
Cristian Mammoli
c.mammoli at apra.it
Mon Jul 27 10:28:42 UTC 2015
> the new template has the sysprep fields filled correctly?
Well, for the first template I used the "initial run" section in the
pool options, not in the template ones. Anyway I tried to fill the new
template options with the correct settings but it did not sysprep.
I can use "run once" as admin to make the rebased vm sysprep but that
would mean to run them one by one...
> do you see any error in the log?
> can you attach the engine.log for the time user takes vm from pool?
>
Here it is
2015-07-27 12:15:16,807 INFO
[org.ovirt.engine.core.bll.AttachUserToVmFromPoolAndRunCommand]
(ajp--127.0.0.1-8702-10) [4ae1c9cd] Lock Acquired to object EngineLock
[exclusiveLocks= key: d69d8d20-68b7-4fed-9c08-5c2ecb257583 value:
USER_VM_POOL
, sharedLocks= ]
2015-07-27 12:15:16,893 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(ajp--127.0.0.1-8702-10) [4ae1c9cd] START,
IsVmDuringInitiatingVDSCommand( vmId =
bc628809-6018-48d9-a0d0-793d8e2ae60b), log id: 3c0ac499
2015-07-27 12:15:16,895 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(ajp--127.0.0.1-8702-10) [4ae1c9cd] FINISH,
IsVmDuringInitiatingVDSCommand, return: false, log id: 3c0ac499
2015-07-27 12:15:16,948 INFO
[org.ovirt.engine.core.bll.AttachUserToVmFromPoolAndRunCommand]
(ajp--127.0.0.1-8702-10) [4ae1c9cd] Running command:
AttachUserToVmFromPoolAndRunCommand internal: false. Entities affected
: ID: e4c8cd94-a75d-4838-80ff-f311e611aad5 Type: VmPoolAction group
VM_POOL_BASIC_OPERATIONS with role type USER
2015-07-27 12:15:16,989 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(ajp--127.0.0.1-8702-10) [4ae1c9cd] START,
IsVmDuringInitiatingVDSCommand( vmId =
bc628809-6018-48d9-a0d0-793d8e2ae60b), log id: 62940db1
2015-07-27 12:15:16,990 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(ajp--127.0.0.1-8702-10) [4ae1c9cd] FINISH,
IsVmDuringInitiatingVDSCommand, return: false, log id: 62940db1
2015-07-27 12:15:17,020 INFO
[org.ovirt.engine.core.bll.AddPermissionCommand]
(ajp--127.0.0.1-8702-10) [37aaec5a] Running command:
AddPermissionCommand internal: true. Entities affected : ID:
bc628809-6018-48d9-a0d0-793d8e2ae60b Type: VMAction group
MANIPULATE_PERMISSIONS with role type USER
2015-07-27 12:15:17,032 INFO
[org.ovirt.engine.core.bll.AttachUserToVmFromPoolAndRunCommand]
(ajp--127.0.0.1-8702-10) [37aaec5a] Succceeded giving user
d69d8d20-68b7-4fed-9c08-5c2ecb257583 permission to Vm
bc628809-6018-48d9-a0d0-793d8e2ae60b
2015-07-27 12:15:17,052 INFO [org.ovirt.engine.core.bll.RunVmCommand]
(ajp--127.0.0.1-8702-10) [65308fd0] Lock Acquired to object EngineLock
[exclusiveLocks= key: bc628809-6018-48d9-a0d0-793d8e2ae60b value: VM
, sharedLocks= ]
2015-07-27 12:15:17,124 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(ajp--127.0.0.1-8702-10) [65308fd0] START,
IsVmDuringInitiatingVDSCommand( vmId =
bc628809-6018-48d9-a0d0-793d8e2ae60b), log id: 6d6561c
2015-07-27 12:15:17,126 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(ajp--127.0.0.1-8702-10) [65308fd0] FINISH,
IsVmDuringInitiatingVDSCommand, return: false, log id: 6d6561c
2015-07-27 12:15:17,177 INFO [org.ovirt.engine.core.bll.RunVmCommand]
(ajp--127.0.0.1-8702-10) [65308fd0] Running command: RunVmCommand
internal: true. Entities affected : ID:
bc628809-6018-48d9-a0d0-793d8e2ae60b Type: VMAction group
VM_BASIC_OPERATIONS with role type USER
2015-07-27 12:15:17,185 INFO [org.ovirt.engine.core.bll.RunVmCommand]
(ajp--127.0.0.1-8702-10) [65308fd0] Creating stateless snapshot for VM
TestPoolAuto-1 (bc628809-6018-48d9-a0d0-793d8e2ae60b)
2015-07-27 12:15:17,245 INFO
[org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand]
(ajp--127.0.0.1-8702-10) Command 01824f40-86e8-4116-a8c3-f67f20e2e351
persisting async task placeholder for child command
1359dec2-5257-466d-a612-fb78b424d1b5
2015-07-27 12:15:17,280 INFO
[org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand]
(ajp--127.0.0.1-8702-10) Running command:
CreateAllSnapshotsFromVmCommand internal: true. Entities affected : ID:
bc628809-6018-48d9-a0d0-793d8e2ae60b Type: VMAction group
MANIPULATE_VM_SNAPSHOTS with role type USER
2015-07-27 12:15:17,316 INFO
[org.ovirt.engine.core.bll.CreateSnapshotCommand]
(ajp--127.0.0.1-8702-10) [44c90b11] Running command:
CreateSnapshotCommand internal: true. Entities affected : ID:
00000000-0000-0000-0000-000000000000 Type: Storage
2015-07-27 12:15:17,320 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand]
(ajp--127.0.0.1-8702-10) [44c90b11] START, CreateSnapshotVDSCommand(
storagePoolId = 00000002-0002-0002-0002-00000000005e,
ignoreFailoverLimit = false, storageDomainId =
5f20ecf7-7d8d-4dab-857a-6eb1aa9b9842, imageGroupId =
76760a81-ee92-4791-bce2-766d3798ee85, imageSizeInBytes = 42949672960,
volumeFormat = COW, newImageId = 4b802949-5ae2-4a25-bab3-d28db0773d7a,
newImageDescription = , imageId = e694497f-aa78-4481-82f3-148561c18c58,
sourceImageGroupId = 76760a81-ee92-4791-bce2-766d3798ee85), log id: 12d3a44d
2015-07-27 12:15:17,340 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand]
(ajp--127.0.0.1-8702-10) [44c90b11] -- executeIrsBrokerCommand: calling
'createVolume' with two new parameters: description and UUID
2015-07-27 12:15:18,248 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand]
(ajp--127.0.0.1-8702-10) [44c90b11] FINISH, CreateSnapshotVDSCommand,
return: 4b802949-5ae2-4a25-bab3-d28db0773d7a, log id: 12d3a44d
2015-07-27 12:15:18,263 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(ajp--127.0.0.1-8702-10) [44c90b11] CommandAsyncTask::Adding
CommandMultiAsyncTasks object for command
d8318acc-d96c-4958-a8c5-1f93cad54765
2015-07-27 12:15:18,270 INFO
[org.ovirt.engine.core.bll.CommandMultiAsyncTasks]
(ajp--127.0.0.1-8702-10) [44c90b11] CommandMultiAsyncTasks::AttachTask:
Attaching task 4ddc7a8b-f741-4478-bd40-05e857d5efb9 to command
d8318acc-d96c-4958-a8c5-1f93cad54765.
2015-07-27 12:15:18,294 INFO
[org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
(ajp--127.0.0.1-8702-10) [44c90b11] Adding task
4ddc7a8b-f741-4478-bd40-05e857d5efb9 (Parent Command RunVm, Parameters
Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters),
polling hasn't started yet..
2015-07-27 12:15:18,347 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(ajp--127.0.0.1-8702-10) Correlation ID: 65308fd0, Job ID:
679f1cd8-2ffe-4e16-bc51-857ffab80eab, Call Stack: null, Custom Event ID:
-1, Message: VM TestPoolAuto-1 was restarted on Host <UNKNOWN>
2015-07-27 12:15:18,356 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (ajp--127.0.0.1-8702-10)
BaseAsyncTask::startPollingTask: Starting to poll task
4ddc7a8b-f741-4478-bd40-05e857d5efb9.
2015-07-27 12:15:18,397 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(ajp--127.0.0.1-8702-10) Correlation ID: 4ae1c9cd, Job ID:
679f1cd8-2ffe-4e16-bc51-857ffab80eab, Call Stack: null, Custom Event ID:
-1, Message: Attaching User c.mammoli to VM TestPoolAuto-1 in VM Pool
TestPoolAuto was initiated by c.mammoli at apra.it.
2015-07-27 12:15:18,409 INFO
[org.ovirt.engine.core.bll.AttachUserToVmFromPoolAndRunCommand]
(ajp--127.0.0.1-8702-10) Lock freed to object EngineLock
[exclusiveLocks= key: d69d8d20-68b7-4fed-9c08-5c2ecb257583 value:
USER_VM_POOL
, sharedLocks= ]
2015-07-27 12:15:27,422 INFO
[org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
(DefaultQuartzScheduler_Worker-38) [48348cff] Polling and updating Async
Tasks: 1 tasks, 1 tasks to poll now
2015-07-27 12:15:27,434 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(DefaultQuartzScheduler_Worker-38) [48348cff] SPMAsyncTask::PollTask:
Polling task 4ddc7a8b-f741-4478-bd40-05e857d5efb9 (Parent Command RunVm,
Parameters Type
org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned
status finished, result 'success'.
2015-07-27 12:15:27,444 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(DefaultQuartzScheduler_Worker-38) [48348cff]
BaseAsyncTask::onTaskEndSuccess: Task
4ddc7a8b-f741-4478-bd40-05e857d5efb9 (Parent Command RunVm, Parameters
Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended
successfully.
2015-07-27 12:15:27,454 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(DefaultQuartzScheduler_Worker-38) [48348cff]
CommandAsyncTask::endActionIfNecessary: All tasks of command
d8318acc-d96c-4958-a8c5-1f93cad54765 has ended -> executing endAction
2015-07-27 12:15:27,462 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(DefaultQuartzScheduler_Worker-38) [48348cff]
CommandAsyncTask::endAction: Ending action for 1 tasks (command ID:
d8318acc-d96c-4958-a8c5-1f93cad54765): calling endAction .
2015-07-27 12:15:27,469 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(org.ovirt.thread.pool-8-thread-36) [48348cff]
CommandAsyncTask::endCommandAction [within thread] context: Attempting
to endAction RunVm, executionIndex: 0
2015-07-27 12:15:27,492 INFO [org.ovirt.engine.core.bll.RunVmCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] Ending command
successfully: org.ovirt.engine.core.bll.RunVmCommand
2015-07-27 12:15:27,498 INFO
[org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand]
(org.ovirt.thread.pool-8-thread-36) Ending command successfully:
org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand
2015-07-27 12:15:27,509 INFO
[org.ovirt.engine.core.bll.CreateSnapshotCommand]
(org.ovirt.thread.pool-8-thread-36) [44c90b11] Ending command
successfully: org.ovirt.engine.core.bll.CreateSnapshotCommand
2015-07-27 12:15:27,516 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand]
(org.ovirt.thread.pool-8-thread-36) [44c90b11] START,
GetImageInfoVDSCommand( storagePoolId =
00000002-0002-0002-0002-00000000005e, ignoreFailoverLimit = false,
storageDomainId = 5f20ecf7-7d8d-4dab-857a-6eb1aa9b9842, imageGroupId =
76760a81-ee92-4791-bce2-766d3798ee85, imageId =
4b802949-5ae2-4a25-bab3-d28db0773d7a), log id: 37defe64
2015-07-27 12:15:28,543 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand]
(org.ovirt.thread.pool-8-thread-36) [44c90b11] FINISH,
GetImageInfoVDSCommand, return:
org.ovirt.engine.core.common.businessentities.DiskImage at 95824dd7, log
id: 37defe64
2015-07-27 12:15:28,563 INFO
[org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand]
(org.ovirt.thread.pool-8-thread-36) Lock freed to object EngineLock
[exclusiveLocks= key: bc628809-6018-48d9-a0d0-793d8e2ae60b value: VM
, sharedLocks= ]
2015-07-27 12:15:28,593 INFO [org.ovirt.engine.core.bll.RunVmCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] Lock Acquired to object
EngineLock [exclusiveLocks= key: bc628809-6018-48d9-a0d0-793d8e2ae60b
value: VM
, sharedLocks= ]
2015-07-27 12:15:28,637 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] START,
IsVmDuringInitiatingVDSCommand( vmId =
bc628809-6018-48d9-a0d0-793d8e2ae60b), log id: 57b435ef
2015-07-27 12:15:28,645 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] FINISH,
IsVmDuringInitiatingVDSCommand, return: false, log id: 57b435ef
2015-07-27 12:15:28,679 INFO [org.ovirt.engine.core.bll.RunVmCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] Running command:
RunVmCommand internal: true. Entities affected : ID:
bc628809-6018-48d9-a0d0-793d8e2ae60b Type: VMAction group
VM_BASIC_OPERATIONS with role type USER
2015-07-27 12:15:28,707 INFO
[org.ovirt.engine.core.bll.scheduling.policyunits.HaReservationWeightPolicyUnit]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] Started HA reservation
scoring method
2015-07-27 12:15:28,715 INFO [org.ovirt.engine.core.bll.RunVmCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] Running VM with attached
cd it_windows_7_professional_with_sp1_vl_build_x64_dvd_u_677841.iso
2015-07-27 12:15:28,718 INFO
[org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] START,
UpdateVmDynamicDataVDSCommand(HostName = kvm01, HostId =
d2b7c9d1-6e5b-4222-a5ee-9a37ba8abcfe,
vmDynamic=org.ovirt.engine.core.common.businessentities.VmDynamic at 2f8b9428),
log id: 215bb51c
2015-07-27 12:15:28,723 INFO
[org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] FINISH,
UpdateVmDynamicDataVDSCommand, log id: 215bb51c
2015-07-27 12:15:28,730 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] START,
IsoPrefixVDSCommand(HostName = kvm01, HostId =
d2b7c9d1-6e5b-4222-a5ee-9a37ba8abcfe,
storagePoolId=00000002-0002-0002-0002-00000000005e), log id: 36f416ec
2015-07-27 12:15:28,739 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] FINISH,
IsoPrefixVDSCommand, return:
/rhev/data-center/mnt/192.168.18.101:_mnt_zpool01_zfs__nfs__01/66fe6211-6f52-492e-9b59-efbf09b2cb52/images/11111111-1111-1111-1111-111111111111,
log id: 36f416ec
2015-07-27 12:15:28,759 INFO
[org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] START,
CreateVmVDSCommand(HostName = kvm01, HostId =
d2b7c9d1-6e5b-4222-a5ee-9a37ba8abcfe,
vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b, vm=VM [TestPoolAuto-1]), log
id: 707f395a
2015-07-27 12:15:28,770 WARN
[org.ovirt.engine.core.vdsbroker.vdsbroker.SysprepHandler]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] Could not find value for
key user. Going to use default value of:
2015-07-27 12:15:28,780 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromSysPrepVDSCommand] (org.ovirt.thread.pool-8-thread-36)
[65308fd0] START, CreateVmFromSysPrepVDSCommand(HostName = kvm01, HostId
= d2b7c9d1-6e5b-4222-a5ee-9a37ba8abcfe,
vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b, vm=VM [TestPoolAuto-1]), log
id: 28780d5f
2015-07-27 12:15:28,830 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0]
org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromSysPrepVDSCommand
kvmEnable=true,keyboardLayout=en-us,nice=0,displayNetwork=ovirtmgmt,copyPasteEnable=true,timeOffset=7200,transparentHugePages=true,vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,acpiEnable=true,custom={device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1ac=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=0d2383cf-4790-4d3a-b62d-c78d1ec8e1ac, 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=ide0, customProperties={},
snapshotId=null, logicalName=null},
device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1acdevice_5726451e-8bd2-4869-90e4-58dd83e0f707device_38f7dd0b-94be-4eba-9792-8978fadce286=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=38f7dd0b-94be-4eba-9792-8978fadce286, 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},
device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1acdevice_5726451e-8bd2-4869-90e4-58dd83e0f707device_38f7dd0b-94be-4eba-9792-8978fadce286device_7fa37687-1772-4aee-9018-5f121e12e717=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=7fa37687-1772-4aee-9018-5f121e12e717, 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},
device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1acdevice_5726451e-8bd2-4869-90e4-58dd83e0f707=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=5726451e-8bd2-4869-90e4-58dd83e0f707, 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}},spiceSslCipherSuite=DEFAULT,memSize=4096,smp=2,emulatedMachine=rhel6.5.0,vmType=kvm,memGuaranteedSize=1024,display=qxl,smartcardEnable=false,bootMenuEnable=false,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,smpCoresPerSocket=1,maxVCpus=16,hypervEnable=true,devices=[{index=0,
model=ich9-ehci1, address={bus=0x00, domain=0x0000, type=pci, slot=0x08,
function=0x7}, specParams={}, device=usb, type=controller,
deviceId=46b1c50e-76bf-47a4-b10a-1d98ad138fa5}, {shared=false,
iface=fdc, index=0,
specParams={vmPayload={file={Unattend.xml=PD94bWwgdmVyc2lvbj0iMS4wIiBlbmNvZGluZz0iVVRGLTgiPz4NCjx1bmF0dGVuZCB4bWxucz0idXJuOnNjaGVtYXMtbWljcm9zb2Z0LWNvbTp1bmF0dGVuZCI+DQogICAgPHNldHRpbmdzIHBhc3M9IndpbmRvd3NQRSI+DQogICAgICAgIDxjb21wb25lbnQgbmFtZT0iTWljcm9zb2Z0LVdpbmRvd3MtU2V0dXAiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPFVzZXJEYXRhPg0KICAgICAgICAgICAgICAgIDxQcm9kdWN0S2V5Pg0KICAgICAgICAgICAgICAgICAgICA8S2V5PjwhW0NEQVRBW3ZyN2Q5LWs2eXZ2LXFydzI0LWNxNnc4LWJoZmdyXV0+PC9LZXk+DQogICAgICAgICAgICAgICAgPC9Qcm9kdWN0S2V5Pg0KICAgICAgICAgICAgICAgIDxBY2NlcHRFdWxhPnRydWU8L0FjY2VwdEV1bGE+DQogICAgICAgICAgICAgICAgPEZ1bGxOYW1lPiJ1c2VyIjwvRnVsbE5hbWU+DQogICAgICAgICAgICAgICAgPE9yZ2FuaXphdGlvbj48IVtDREFUQVthcHJhLml0XV0+PC9Pcmdhbml6YXRpb24+DQogICAgICAgICAgICA8L1VzZXJEYXRhPg0KICAgICAgICAgICAgPEltYWdlSW5zdGFsbD4NCiAgICAgICAgICAgICAgICA8T1NJbWFnZT4NCiAgICAgICAgICAgICAgICAgICAgPEluc3RhbGxUb0F2YWlsYWJsZVBhcnRpdGlvbj50cnVlPC9JbnN0YWxsVG9BdmFpbGFibGVQYXJ0aXRpb24+DQogICAgICAgICAgICAgICAgPC9PU0ltYWdlPg0KICAgICAgICAgICAgPC9JbWFnZUluc3RhbGw+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLUludGVybmF0aW9uYWwtQ29yZS1XaW5QRSIgcHJvY2Vzc29yQXJjaGl0ZWN0dXJlPSJ4ODYiIHB1YmxpY0tleVRva2VuPSIzMWJmMzg1NmFkMzY0ZTM1IiBsYW5ndWFnZT0ibmV1dHJhbCIgdmVyc2lvblNjb3BlPSJub25TeFMiIHhtbG5zOndjbT0iaHR0cDovL3NjaGVtYXMubWljcm9zb2Z0LmNvbS9XTUlDb25maWcvMjAwMi9TdGF0ZSIgeG1sbnM6eHNpPSJodHRwOi8vd3d3LnczLm9yZy8yMDAxL1hNTFNjaGVtYS1pbnN0YW5jZSI+DQogICAgICAgICAgICA8U2V0dXBVSUxhbmd1YWdlPg0KICAgICAgICAgICAgICAgIDxVSUxhbmd1YWdlPjwhW0NEQVRBW2VuX1VTXV0+PC9VSUxhbmd1YWdlPg0KICAgICAgICAgICAgPC9TZXR1cFVJTGFuZ3VhZ2U+DQogICAgICAgICAgICA8SW5wdXRMb2NhbGU+PCFbQ0RBVEFbZW5fVVNdXT48L0lucHV0TG9jYWxlPg0KICAgICAgICAgICAgPFVJTGFuZ3VhZ2U+PCFbQ0RBVEFbZW5fVVNdXT48L1VJTGFuZ3VhZ2U+DQogICAgICAgICAgICA8U3lzdGVtTG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9TeXN0ZW1Mb2NhbGU+DQogICAgICAgICAgICA8VXNlckxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvVXNlckxvY2FsZT4NCiAgICAgICAgPC9jb21wb25lbnQ+DQogICAgPC9zZXR0aW5ncz4NCiAgICA8c2V0dGluZ3MgcGFzcz0ic3BlY2lhbGl6ZSI+DQogICAgICAgIDxjb21wb25lbnQgbmFtZT0iTWljcm9zb2Z0LVdpbmRvd3MtU2hlbGwtU2V0dXAiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPERpc3BsYXk+DQogICAgICAgICAgICAgICAgPENvbG9yRGVwdGg+MzI8L0NvbG9yRGVwdGg+DQogICAgICAgICAgICAgICAgPERQST45NjwvRFBJPg0KICAgICAgICAgICAgICAgIDxIb3Jpem9udGFsUmVzb2x1dGlvbj4xMDI0PC9Ib3Jpem9udGFsUmVzb2x1dGlvbj4NCiAgICAgICAgICAgICAgICA8UmVmcmVzaFJhdGU+NzU8L1JlZnJlc2hSYXRlPg0KICAgICAgICAgICAgICAgIDxWZXJ0aWNhbFJlc29sdXRpb24+NzY4PC9WZXJ0aWNhbFJlc29sdXRpb24+DQogICAgICAgICAgICA8L0Rpc3BsYXk+DQogICAgICAgICAgICA8Q29tcHV0ZXJOYW1lPjwhW0NEQVRBW1Rlc3RQb29sQXV0by0xXV0+PC9Db21wdXRlck5hbWU+DQogICAgICAgICAgICA8VGltZVpvbmU+PCFbQ0RBVEFbR01UIFN0YW5kYXJkIFRpbWVdXT48L1RpbWVab25lPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVc2VyTG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9Vc2VyTG9jYWxlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVJTGFuZ3VhZ2U+PCFbQ0RBVEFbZW5fVVNdXT48L1VJTGFuZ3VhZ2U+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVVuYXR0ZW5kZWRKb2luIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9ImFtZDY0IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElkZW50aWZpY2F0aW9uPg0KICAgICAgICAgICAgICAgIDxDcmVkZW50aWFscz4NCiAgICAgICAgICAgICAgICAgICAgPERvbWFpbj48IVtDREFUQVtdXT48L0RvbWFpbj4NCiAgICAgICAgICAgICAgICAgICAgPFBhc3N3b3JkPjwhW0NEQVRBW11dPjwvUGFzc3dvcmQ+DQogICAgICAgICAgICAgICAgICAgIDxVc2VybmFtZT48IVtDREFUQVtdXT48L1VzZXJuYW1lPg0KICAgICAgICAgICAgICAgIDwvQ3JlZGVudGlhbHM+DQogICAgICAgICAgICAgICAgPEpvaW5Eb21haW4+PCFbQ0RBVEFbXV0+PC9Kb2luRG9tYWluPg0KICAgICAgICAgICAgICAgIDxNYWNoaW5lT2JqZWN0T1U+PCFbQ0RBVEFbXV0+PC9NYWNoaW5lT2JqZWN0T1U+DQogICAgICAgICAgICA8L0lkZW50aWZpY2F0aW9uPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICA8L3NldHRpbmdzPg0KICAgIDxzZXR0aW5ncyBwYXNzPSJvb2JlU3lzdGVtIj4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVc2VyTG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9Vc2VyTG9jYWxlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVJTGFuZ3VhZ2U+PCFbQ0RBVEFbZW5fVVNdXT48L1VJTGFuZ3VhZ2U+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVNoZWxsLVNldHVwIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9ImFtZDY0IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPE9PQkU+DQogICAgICAgICAgICAgICAgPFByb3RlY3RZb3VyUEM+MjwvUHJvdGVjdFlvdXJQQz4NCiAgICAgICAgICAgICAgICA8TmV0d29ya0xvY2F0aW9uPldvcms8L05ldHdvcmtMb2NhdGlvbj4NCiAgICAgICAgICAgICAgICA8SGlkZUVVTEFQYWdlPnRydWU8L0hpZGVFVUxBUGFnZT4NCiAgICAgICAgICAgIDwvT09CRT4NCiAgICAgICAgICAgIDxVc2VyQWNjb3VudHM+DQogICAgICAgICAgICAgICAgPEFkbWluaXN0cmF0b3JQYXNzd29yZD4NCiAgICAgICAgICAgICAgICAgICAgPFZhbHVlPjwhW0NEQVRBW1Jmb2Nlc3ExMjNdXT48L1ZhbHVlPg0KICAgICAgICAgICAgICAgICAgICA8UGxhaW5UZXh0PnRydWU8L1BsYWluVGV4dD4NCiAgICAgICAgICAgICAgICA8L0FkbWluaXN0cmF0b3JQYXNzd29yZD4NCiAgICAgICAgICAgICAgICA8TG9jYWxBY2NvdW50cz4NCiAgICAgICAgICAgICAgICAgICAgPExvY2FsQWNjb3VudCB3Y206YWN0aW9uPSJhZGQiPg0KICAgICAgICAgICAgICAgICAgICAgICAgPFBhc3N3b3JkPg0KICAgICAgICAgICAgICAgICAgICAgICAgICAgIDxWYWx1ZT48IVtDREFUQVtSZm9jZXNxMTIzXV0+PC9WYWx1ZT4NCiAgICAgICAgICAgICAgICAgICAgICAgICAgICA8UGxhaW5UZXh0PnRydWU8L1BsYWluVGV4dD4NCiAgICAgICAgICAgICAgICAgICAgICAgIDwvUGFzc3dvcmQ+DQogICAgICAgICAgICAgICAgICAgICAgICA8R3JvdXA+YWRtaW5pc3RyYXRvcnM8L0dyb3VwPg0KICAgICAgICAgICAgICAgICAgICAgICAgPE5hbWU+dXNlcjwvTmFtZT4NCiAgICAgICAgICAgICAgICAgICAgICAgIDxEaXNwbGF5TmFtZT51c2VyPC9EaXNwbGF5TmFtZT4NCiAgICAgICAgICAgICAgICAgICAgPC9Mb2NhbEFjY291bnQ+DQogICAgICAgICAgICAgICAgPC9Mb2NhbEFjY291bnRzPg0KICAgICAgICAgICAgPC9Vc2VyQWNjb3VudHM+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgIDwvc2V0dGluZ3M+DQogICAgPGNwaTpvZmZsaW5lSW1hZ2UgY3BpOnNvdXJjZT0id2ltOmQ6L3NvdXJjZXMvaW5zdGFsbC53aW0jV2luZG93cyA3IEVOVEVSUFJJU0UiIHhtbG5zOmNwaT0idXJuOnNjaGVtYXMtbWljcm9zb2Z0LWNvbTpjcGkiLz4NCjwvdW5hdHRlbmQ+DQo=}}},
path=, device=floppy, type=disk, readonly=true,
deviceId=fb539842-44d0-4e62-bce6-a298099f3271}, {specParams={vram=32768,
heads=1}, device=qxl, type=video,
deviceId=663b34a7-736f-407b-a095-f255416e2e1e}, {shared=false,
bootOrder=2, iface=ide, index=2, address={unit=0, bus=1, target=0,
controller=0, type=drive},
specParams={path=it_windows_7_professional_with_sp1_vl_build_x64_dvd_u_677841.iso},
path=/rhev/data-center/mnt/192.168.18.101:_mnt_zpool01_zfs__nfs__01/66fe6211-6f52-492e-9b59-efbf09b2cb52/images/11111111-1111-1111-1111-111111111111/it_windows_7_professional_with_sp1_vl_build_x64_dvd_u_677841.iso,
device=cdrom, type=disk, readonly=true,
deviceId=77ac75cd-3033-4b32-82d9-1d5b16402f87}, {shared=false, index=0,
volumeID=4b802949-5ae2-4a25-bab3-d28db0773d7a, propagateErrors=off,
format=cow, type=disk, iface=virtio, bootOrder=1, address={bus=0x00,
domain=0x0000, type=pci, slot=0x07, function=0x0},
domainID=5f20ecf7-7d8d-4dab-857a-6eb1aa9b9842,
imageID=76760a81-ee92-4791-bce2-766d3798ee85, specParams={},
optional=false, device=disk,
poolID=00000002-0002-0002-0002-00000000005e, readonly=false,
deviceId=76760a81-ee92-4791-bce2-766d3798ee85}, {nicModel=pv,
address={bus=0x00, domain=0x0000, type=pci, slot=0x03, function=0x0},
specParams={outbound={}, inbound={}}, macAddr=00:01:a4:a3:96:08,
device=bridge, linkActive=true, type=interface,
filter=vdsm-no-mac-spoofing, network=ovirtmgmt,
deviceId=9be5f331-21d1-473f-9acf-7b75af1a2a71}, {index=0,
model=ich9-uhci2, address={bus=0x00, domain=0x0000, type=pci, slot=0x08,
function=0x1}, specParams={}, device=usb, type=controller,
deviceId=0c634be5-68b0-4aca-84e5-39be52c1aad7}, {index=0,
model=ich9-uhci3, address={bus=0x00, domain=0x0000, type=pci, slot=0x08,
function=0x2}, specParams={}, device=usb, type=controller,
deviceId=a3efe93f-f96a-44b3-a61a-dbb58e13cad1}, {index=0,
model=ich9-uhci1, address={bus=0x00, domain=0x0000, type=pci,
multifunction=on, slot=0x08, function=0x0}, specParams={}, device=usb,
type=controller, deviceId=b9de904c-e90f-4f0a-aa3a-e336e76e1161},
{bus=usb, specParams={}, device=spicevmc, type=redir,
deviceId=9526bf90-1421-4d66-a55b-4999725347d1}, {bus=usb, specParams={},
device=spicevmc, type=redir,
deviceId=00a043f7-7d19-40b8-a674-c1d0addf3b23}, {bus=usb, specParams={},
device=spicevmc, type=redir,
deviceId=330d6439-46b5-4924-88a5-752aaafc42a0}, {bus=usb, specParams={},
device=spicevmc, type=redir,
deviceId=4678aa61-99ea-47ec-a34e-47923f8a857d}, {address={bus=0x00,
domain=0x0000, type=pci, slot=0x06, function=0x0}, specParams={},
device=virtio-serial, type=controller,
deviceId=e61366be-a79d-428e-8020-4b95e017a687}],vmName=TestPoolAuto-1,cpuType=Conroe,fileTransferEnable=true
2015-07-27 12:15:29,123 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromSysPrepVDSCommand] (org.ovirt.thread.pool-8-thread-36)
[65308fd0] FINISH, CreateVmFromSysPrepVDSCommand, log id: 28780d5f
2015-07-27 12:15:29,139 INFO
[org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] FINISH,
CreateVmVDSCommand, return: WaitForLaunch, log id: 707f395a
2015-07-27 12:15:29,145 INFO [org.ovirt.engine.core.bll.RunVmCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] Lock freed to object
EngineLock [exclusiveLocks= key: bc628809-6018-48d9-a0d0-793d8e2ae60b
value: VM
, sharedLocks= ]
2015-07-27 12:15:29,164 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(org.ovirt.thread.pool-8-thread-36) [65308fd0]
CommandAsyncTask::HandleEndActionResult [within thread]: endAction for
action type RunVm completed, handling the result.
2015-07-27 12:15:29,171 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(org.ovirt.thread.pool-8-thread-36) [65308fd0]
CommandAsyncTask::HandleEndActionResult [within thread]: endAction for
action type RunVm succeeded, clearing tasks.
2015-07-27 12:15:29,190 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(org.ovirt.thread.pool-8-thread-36) [65308fd0]
SPMAsyncTask::ClearAsyncTask: Attempting to clear task
4ddc7a8b-f741-4478-bd40-05e857d5efb9
2015-07-27 12:15:29,197 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] START,
SPMClearTaskVDSCommand( storagePoolId =
00000002-0002-0002-0002-00000000005e, ignoreFailoverLimit = false,
taskId = 4ddc7a8b-f741-4478-bd40-05e857d5efb9), log id: 20a0441a
2015-07-27 12:15:29,207 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] START,
HSMClearTaskVDSCommand(HostName = kvm02, HostId =
4aeb8095-1198-4afe-aab2-d9c6408c88c2,
taskId=4ddc7a8b-f741-4478-bd40-05e857d5efb9), log id: 79d717b1
2015-07-27 12:15:29,236 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] FINISH,
HSMClearTaskVDSCommand, log id: 79d717b1
2015-07-27 12:15:29,242 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
(org.ovirt.thread.pool-8-thread-36) [65308fd0] FINISH,
SPMClearTaskVDSCommand, log id: 20a0441a
2015-07-27 12:15:29,252 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(org.ovirt.thread.pool-8-thread-36) [65308fd0]
BaseAsyncTask::removeTaskFromDB: Removed task
4ddc7a8b-f741-4478-bd40-05e857d5efb9 from DataBase
2015-07-27 12:15:29,259 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(org.ovirt.thread.pool-8-thread-36) [65308fd0]
CommandAsyncTask::HandleEndActionResult [within thread]: Removing
CommandMultiAsyncTasks object for entity
d8318acc-d96c-4958-a8c5-1f93cad54765
2015-07-27 12:15:30,950 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand]
(DefaultQuartzScheduler_Worker-74) [62ecfb7d] START,
FullListVdsCommand(HostName = kvm01, HostId =
d2b7c9d1-6e5b-4222-a5ee-9a37ba8abcfe,
vds=Host[kvm01,d2b7c9d1-6e5b-4222-a5ee-9a37ba8abcfe],
vmIds=[bc628809-6018-48d9-a0d0-793d8e2ae60b]), log id: ee7263c
2015-07-27 12:15:30,961 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand]
(DefaultQuartzScheduler_Worker-74) [62ecfb7d] FINISH,
FullListVdsCommand, return: [{acpiEnable=true,
emulatedMachine=rhel6.5.0, vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
memGuaranteedSize=1024, transparentHugePages=true, displaySecurePort=-1,
spiceSslCipherSuite=DEFAULT, cpuType=Conroe, smp=2,
smartcardEnable=false, hypervEnable=true,
custom={device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1ac=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=0d2383cf-4790-4d3a-b62d-c78d1ec8e1ac, 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=ide0, customProperties={},
snapshotId=null, logicalName=null},
device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1acdevice_5726451e-8bd2-4869-90e4-58dd83e0f707device_38f7dd0b-94be-4eba-9792-8978fadce286device_7fa37687-1772-4aee-9018-5f121e12e717=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=7fa37687-1772-4aee-9018-5f121e12e717, 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},
device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1acdevice_5726451e-8bd2-4869-90e4-58dd83e0f707device_38f7dd0b-94be-4eba-9792-8978fadce286=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=38f7dd0b-94be-4eba-9792-8978fadce286, 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},
device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1acdevice_5726451e-8bd2-4869-90e4-58dd83e0f707=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=5726451e-8bd2-4869-90e4-58dd83e0f707, 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}}, vmType=kvm, memSize=4096,
smpCoresPerSocket=1, vmName=TestPoolAuto-1, nice=0,
status=WaitForLaunch, bootMenuEnable=false, pid=0, copyPasteEnable=true,
displayIp=192.168.0.55, displayPort=-1, guestDiskMapping={}, clientIp=,
fileTransferEnable=true, nicModel=rtl8139,pv, keyboardLayout=en-us,
kvmEnable=true, displayNetwork=ovirtmgmt,
devices=[Ljava.lang.Object;@697f34c8, timeOffset=7200, maxVCpus=16,
spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,
display=qxl}], log id: ee7263c
2015-07-27 12:15:31,022 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-74) [62ecfb7d] Received a floppy Device
without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device
2015-07-27 12:15:31,030 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-74) [62ecfb7d] Received a qxl Device
without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device:
{device=qxl, specParams={vram=32768, heads=1}, deviceType=video,
type=video, deviceId=663b34a7-736f-407b-a095-f255416e2e1e}
2015-07-27 12:15:31,041 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-74) [62ecfb7d] Received a spicevmc Device
without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device: {bus=usb,
specParams={}, deviceType=redir,
deviceId=9526bf90-1421-4d66-a55b-4999725347d1, device=spicevmc, type=redir}
2015-07-27 12:15:31,051 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-74) [62ecfb7d] Received a spicevmc Device
without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device: {bus=usb,
specParams={}, deviceType=redir,
deviceId=00a043f7-7d19-40b8-a674-c1d0addf3b23, device=spicevmc, type=redir}
2015-07-27 12:15:31,056 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-74) [62ecfb7d] Received a spicevmc Device
without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device: {bus=usb,
specParams={}, deviceType=redir,
deviceId=330d6439-46b5-4924-88a5-752aaafc42a0, device=spicevmc, type=redir}
2015-07-27 12:15:31,057 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-74) [62ecfb7d] Received a spicevmc Device
without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device: {bus=usb,
specParams={}, deviceType=redir,
deviceId=4678aa61-99ea-47ec-a34e-47923f8a857d, device=spicevmc, type=redir}
2015-07-27 12:15:34,128 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-98) VM TestPoolAuto-1
bc628809-6018-48d9-a0d0-793d8e2ae60b moved from WaitForLaunch --> PoweringUp
2015-07-27 12:15:34,129 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand]
(DefaultQuartzScheduler_Worker-98) START, FullListVdsCommand(HostName =
kvm01, HostId = d2b7c9d1-6e5b-4222-a5ee-9a37ba8abcfe,
vds=Host[kvm01,d2b7c9d1-6e5b-4222-a5ee-9a37ba8abcfe],
vmIds=[bc628809-6018-48d9-a0d0-793d8e2ae60b]), log id: 2353a676
2015-07-27 12:15:34,141 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand]
(DefaultQuartzScheduler_Worker-98) FINISH, FullListVdsCommand, return:
[{acpiEnable=true, emulatedMachine=rhel6.5.0,
vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b, memGuaranteedSize=1024,
transparentHugePages=true, displaySecurePort=5900,
spiceSslCipherSuite=DEFAULT, cpuType=Conroe, smp=2, pauseCode=NOERR,
smartcardEnable=false, hypervEnable=true,
custom={device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1ac=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=0d2383cf-4790-4d3a-b62d-c78d1ec8e1ac, 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=ide0, customProperties={},
snapshotId=null, logicalName=null},
device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1acdevice_5726451e-8bd2-4869-90e4-58dd83e0f707device_38f7dd0b-94be-4eba-9792-8978fadce286device_7fa37687-1772-4aee-9018-5f121e12e717=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=7fa37687-1772-4aee-9018-5f121e12e717, 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},
device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1acdevice_5726451e-8bd2-4869-90e4-58dd83e0f707device_38f7dd0b-94be-4eba-9792-8978fadce286=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=38f7dd0b-94be-4eba-9792-8978fadce286, 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},
device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1acdevice_5726451e-8bd2-4869-90e4-58dd83e0f707=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=5726451e-8bd2-4869-90e4-58dd83e0f707, 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}}, vmType=kvm, memSize=4096,
smpCoresPerSocket=1, vmName=TestPoolAuto-1, nice=0, status=Up,
bootMenuEnable=false, pid=18291, copyPasteEnable=true,
displayIp=192.168.0.55, displayPort=-1, guestDiskMapping={}, clientIp=,
fileTransferEnable=true, nicModel=rtl8139,pv, keyboardLayout=en-us,
kvmEnable=true, displayNetwork=ovirtmgmt,
devices=[Ljava.lang.Object;@48d59f18, timeOffset=7200, maxVCpus=16,
spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,
display=qxl}], log id: 2353a676
2015-07-27 12:15:34,204 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-98) Received a memballoon Device without
an address when processing VM bc628809-6018-48d9-a0d0-793d8e2ae60b
devices, skipping device: {device=memballoon, specParams={model=none},
deviceType=balloon, type=balloon, alias=balloon0}
2015-07-27 12:15:34,213 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-98) Received a spicevmc Device without an
address when processing VM bc628809-6018-48d9-a0d0-793d8e2ae60b devices,
skipping device: {bus=usb, specParams={}, deviceType=redir,
deviceId=9526bf90-1421-4d66-a55b-4999725347d1, device=spicevmc, type=redir}
2015-07-27 12:15:34,224 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-98) Received a spicevmc Device without an
address when processing VM bc628809-6018-48d9-a0d0-793d8e2ae60b devices,
skipping device: {bus=usb, specParams={}, deviceType=redir,
deviceId=00a043f7-7d19-40b8-a674-c1d0addf3b23, device=spicevmc, type=redir}
2015-07-27 12:15:34,234 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-98) Received a spicevmc Device without an
address when processing VM bc628809-6018-48d9-a0d0-793d8e2ae60b devices,
skipping device: {bus=usb, specParams={}, deviceType=redir,
deviceId=330d6439-46b5-4924-88a5-752aaafc42a0, device=spicevmc, type=redir}
2015-07-27 12:15:34,248 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-98) Received a spicevmc Device without an
address when processing VM bc628809-6018-48d9-a0d0-793d8e2ae60b devices,
skipping device: {bus=usb, specParams={}, deviceType=redir,
deviceId=4678aa61-99ea-47ec-a34e-47923f8a857d, device=spicevmc, type=redir}
2015-07-27 12:15:34,259 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-98) Received a spice Device without an
address when processing VM bc628809-6018-48d9-a0d0-793d8e2ae60b devices,
skipping device: {device=spice, specParams={displayNetwork=ovirtmgmt,
spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,
keyMap=en-us, displayIp=192.168.0.55, copyPasteEnable=true},
deviceType=graphics, type=graphics, tlsPort=5900}
2015-07-27 12:16:29,505 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-13) [4ec0a66d] VM TestPoolAuto-1
bc628809-6018-48d9-a0d0-793d8e2ae60b moved from PoweringUp --> Up
2015-07-27 12:16:29,531 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(DefaultQuartzScheduler_Worker-13) [4ec0a66d] Correlation ID: 65308fd0,
Job ID: 679f1cd8-2ffe-4e16-bc51-857ffab80eab, Call Stack: null, Custom
Event ID: -1, Message: VM TestPoolAuto-1 was restarted on Host kvm01 as
stateless
2015-07-27 12:16:42,758 INFO
[org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
(DefaultQuartzScheduler_Worker-15) [2994ee4] Setting new tasks map. The
map contains now 0 tasks
2015-07-27 12:16:42,759 INFO
[org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
(DefaultQuartzScheduler_Worker-15) [2994ee4] Cleared all tasks of pool
00000002-0002-0002-0002-00000000005e.
2015-07-27 12:16:46,746 INFO
[org.ovirt.engine.core.bll.SetVmTicketCommand] (ajp--127.0.0.1-8702-6)
[46c9878e] Running command: SetVmTicketCommand internal: false. Entities
affected : ID: bc628809-6018-48d9-a0d0-793d8e2ae60b Type: VMAction
group CONNECT_TO_VM with role type USER
2015-07-27 12:16:46,753 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand]
(ajp--127.0.0.1-8702-6) [46c9878e] START, SetVmTicketVDSCommand(HostName
= kvm01, HostId = d2b7c9d1-6e5b-4222-a5ee-9a37ba8abcfe,
vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b, ticket=XEwZCmrBC8fS,
validTime=120,m userName=admin,
userId=fdfc627c-d875-11e0-90f0-83df133b58cc), log id: 4f208f2c
2015-07-27 12:16:46,814 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand]
(ajp--127.0.0.1-8702-6) [46c9878e] FINISH, SetVmTicketVDSCommand, log
id: 4f208f2c
2015-07-27 12:16:46,829 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(ajp--127.0.0.1-8702-6) [46c9878e] Correlation ID: 46c9878e, Call Stack:
null, Custom Event ID: -1, Message: user admin at internal initiated
console session for VM TestPoolAuto-1
2015-07-27 12:17:33,917 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand]
(DefaultQuartzScheduler_Worker-21) [1f94e1d1] START,
FullListVdsCommand(HostName = kvm01, HostId =
d2b7c9d1-6e5b-4222-a5ee-9a37ba8abcfe,
vds=Host[kvm01,d2b7c9d1-6e5b-4222-a5ee-9a37ba8abcfe],
vmIds=[bc628809-6018-48d9-a0d0-793d8e2ae60b]), log id: 1cb36bbe
2015-07-27 12:17:33,939 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand]
(DefaultQuartzScheduler_Worker-21) [1f94e1d1] FINISH,
FullListVdsCommand, return: [{acpiEnable=true,
emulatedMachine=rhel6.5.0, vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
memGuaranteedSize=1024, transparentHugePages=true,
displaySecurePort=5900, spiceSslCipherSuite=DEFAULT, cpuType=Conroe,
smp=2, pauseCode=NOERR, smartcardEnable=false, hypervEnable=true,
custom={device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1ac=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=0d2383cf-4790-4d3a-b62d-c78d1ec8e1ac, 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=ide0, customProperties={},
snapshotId=null, logicalName=null},
device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1acdevice_5726451e-8bd2-4869-90e4-58dd83e0f707device_38f7dd0b-94be-4eba-9792-8978fadce286device_7fa37687-1772-4aee-9018-5f121e12e717=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=7fa37687-1772-4aee-9018-5f121e12e717, 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},
device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1acdevice_5726451e-8bd2-4869-90e4-58dd83e0f707device_38f7dd0b-94be-4eba-9792-8978fadce286=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=38f7dd0b-94be-4eba-9792-8978fadce286, 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},
device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1acdevice_5726451e-8bd2-4869-90e4-58dd83e0f707=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=5726451e-8bd2-4869-90e4-58dd83e0f707, 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}}, vmType=kvm, memSize=4096,
smpCoresPerSocket=1, vmName=TestPoolAuto-1, nice=0, status=Up,
bootMenuEnable=false, pid=18291, copyPasteEnable=true,
displayIp=192.168.0.55, displayPort=-1, guestDiskMapping={}, clientIp=,
fileTransferEnable=true, nicModel=rtl8139,pv, keyboardLayout=en-us,
kvmEnable=true, displayNetwork=ovirtmgmt,
devices=[Ljava.lang.Object;@54e913b4, timeOffset=7200, maxVCpus=16,
spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,
display=qxl}], log id: 1cb36bbe
2015-07-27 12:17:34,000 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-21) [1f94e1d1] Received a memballoon
Device without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device:
{device=memballoon, specParams={model=none}, deviceType=balloon,
type=balloon, alias=balloon0}
2015-07-27 12:17:34,011 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-21) [1f94e1d1] Received a spicevmc Device
without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device: {bus=usb,
specParams={}, deviceType=redir,
deviceId=9526bf90-1421-4d66-a55b-4999725347d1, device=spicevmc, type=redir}
2015-07-27 12:17:34,022 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-21) [1f94e1d1] Received a spicevmc Device
without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device: {bus=usb,
specParams={}, deviceType=redir,
deviceId=00a043f7-7d19-40b8-a674-c1d0addf3b23, device=spicevmc, type=redir}
2015-07-27 12:17:34,039 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-21) [1f94e1d1] Received a spicevmc Device
without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device: {bus=usb,
specParams={}, deviceType=redir,
deviceId=330d6439-46b5-4924-88a5-752aaafc42a0, device=spicevmc, type=redir}
2015-07-27 12:17:34,050 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-21) [1f94e1d1] Received a spicevmc Device
without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device: {bus=usb,
specParams={}, deviceType=redir,
deviceId=4678aa61-99ea-47ec-a34e-47923f8a857d, device=spicevmc, type=redir}
2015-07-27 12:17:34,074 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-21) [1f94e1d1] Received a spice Device
without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device:
{device=spice, specParams={displayNetwork=ovirtmgmt,
spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,
keyMap=en-us, displayIp=192.168.0.55, copyPasteEnable=true},
deviceType=graphics, type=graphics, tlsPort=5900}
2015-07-27 12:19:36,015 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-19) [3ec2eaa1] VM TestPoolAuto-1
bc628809-6018-48d9-a0d0-793d8e2ae60b moved from Up --> RebootInProgress
2015-07-27 12:19:36,023 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand]
(DefaultQuartzScheduler_Worker-19) [3ec2eaa1] START,
FullListVdsCommand(HostName = kvm01, HostId =
d2b7c9d1-6e5b-4222-a5ee-9a37ba8abcfe,
vds=Host[kvm01,d2b7c9d1-6e5b-4222-a5ee-9a37ba8abcfe],
vmIds=[bc628809-6018-48d9-a0d0-793d8e2ae60b]), log id: 4f3a82f4
2015-07-27 12:19:36,191 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand]
(DefaultQuartzScheduler_Worker-19) [3ec2eaa1] FINISH,
FullListVdsCommand, return: [{acpiEnable=true,
emulatedMachine=rhel6.5.0, vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
memGuaranteedSize=1024, transparentHugePages=true,
displaySecurePort=5900, spiceSslCipherSuite=DEFAULT, cpuType=Conroe,
smp=2, pauseCode=NOERR, smartcardEnable=false, hypervEnable=true,
custom={device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1ac=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=0d2383cf-4790-4d3a-b62d-c78d1ec8e1ac, 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=ide0, customProperties={},
snapshotId=null, logicalName=null},
device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1acdevice_5726451e-8bd2-4869-90e4-58dd83e0f707device_38f7dd0b-94be-4eba-9792-8978fadce286device_7fa37687-1772-4aee-9018-5f121e12e717=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=7fa37687-1772-4aee-9018-5f121e12e717, 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},
device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1acdevice_5726451e-8bd2-4869-90e4-58dd83e0f707device_38f7dd0b-94be-4eba-9792-8978fadce286=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=38f7dd0b-94be-4eba-9792-8978fadce286, 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},
device_0d2383cf-4790-4d3a-b62d-c78d1ec8e1acdevice_5726451e-8bd2-4869-90e4-58dd83e0f707=VmDevice
{vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b,
deviceId=5726451e-8bd2-4869-90e4-58dd83e0f707, 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}}, vmType=kvm, memSize=4096,
smpCoresPerSocket=1, vmName=TestPoolAuto-1, nice=0, status=Up,
bootMenuEnable=false, pid=18291, copyPasteEnable=true,
displayIp=192.168.0.55, displayPort=-1, guestDiskMapping={}, clientIp=,
fileTransferEnable=true, nicModel=rtl8139,pv, keyboardLayout=en-us,
kvmEnable=true, displayNetwork=ovirtmgmt,
devices=[Ljava.lang.Object;@a6b89cf, timeOffset=14400, maxVCpus=16,
spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,
display=qxl}], log id: 4f3a82f4
2015-07-27 12:19:36,281 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-19) [3ec2eaa1] Received a memballoon
Device without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device:
{device=memballoon, specParams={model=none}, deviceType=balloon,
type=balloon, alias=balloon0}
2015-07-27 12:19:36,282 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-19) [3ec2eaa1] Received a spicevmc Device
without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device: {bus=usb,
specParams={}, deviceType=redir,
deviceId=9526bf90-1421-4d66-a55b-4999725347d1, device=spicevmc, type=redir}
2015-07-27 12:19:36,284 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-19) [3ec2eaa1] Received a spicevmc Device
without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device: {bus=usb,
specParams={}, deviceType=redir,
deviceId=00a043f7-7d19-40b8-a674-c1d0addf3b23, device=spicevmc, type=redir}
2015-07-27 12:19:36,286 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-19) [3ec2eaa1] Received a spicevmc Device
without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device: {bus=usb,
specParams={}, deviceType=redir,
deviceId=330d6439-46b5-4924-88a5-752aaafc42a0, device=spicevmc, type=redir}
2015-07-27 12:19:36,288 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-19) [3ec2eaa1] Received a spicevmc Device
without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device: {bus=usb,
specParams={}, deviceType=redir,
deviceId=4678aa61-99ea-47ec-a34e-47923f8a857d, device=spicevmc, type=redir}
2015-07-27 12:19:36,290 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-19) [3ec2eaa1] Received a spice Device
without an address when processing VM
bc628809-6018-48d9-a0d0-793d8e2ae60b devices, skipping device:
{device=spice, specParams={displayNetwork=ovirtmgmt,
spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,
keyMap=en-us, displayIp=192.168.0.55, copyPasteEnable=true},
deviceType=graphics, type=graphics, tlsPort=5900}
2015-07-27 12:21:20,949 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-82) [50c1bbd6] VM TestPoolAuto-1
bc628809-6018-48d9-a0d0-793d8e2ae60b moved from RebootInProgress --> Up
2015-07-27 12:21:24,021 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-74) [62ecfb7d] VM TestPoolAuto-1
bc628809-6018-48d9-a0d0-793d8e2ae60b moved from Up --> RebootInProgress
2015-07-27 12:21:51,889 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-43) [5cceecd8] VM TestPoolAuto-1
bc628809-6018-48d9-a0d0-793d8e2ae60b moved from RebootInProgress --> Up
2015-07-27 12:24:26,557 WARN
[org.ovirt.engine.core.bll.SetVmTicketCommand] (ajp--127.0.0.1-8702-3)
[515006c1] User "d69d8d20-68b7-4fed-9c08-5c2ecb257583" is trying to take
the console of virtual machine "bc628809-6018-48d9-a0d0-793d8e2ae60b",
but the console is already taken by user
"fdfc627c-d875-11e0-90f0-83df133b58cc".
2015-07-27 12:24:26,561 WARN
[org.ovirt.engine.core.bll.SetVmTicketCommand] (ajp--127.0.0.1-8702-3)
[515006c1] User "d69d8d20-68b7-4fed-9c08-5c2ecb257583" is trying to take
the console of virtual machine "bc628809-6018-48d9-a0d0-793d8e2ae60b",
but the console is already taken by user
"fdfc627c-d875-11e0-90f0-83df133b58cc".
2015-07-27 12:24:26,563 INFO
[org.ovirt.engine.core.bll.SetVmTicketCommand] (ajp--127.0.0.1-8702-3)
[515006c1] Running command: SetVmTicketCommand internal: false. Entities
affected : ID: bc628809-6018-48d9-a0d0-793d8e2ae60b Type: VMAction
group CONNECT_TO_VM with role type USER, ID:
bc628809-6018-48d9-a0d0-793d8e2ae60b Type: VMAction group
RECONNECT_TO_VM with role type USER
2015-07-27 12:24:26,570 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand]
(ajp--127.0.0.1-8702-3) [515006c1] START, SetVmTicketVDSCommand(HostName
= kvm01, HostId = d2b7c9d1-6e5b-4222-a5ee-9a37ba8abcfe,
vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b, ticket=0y6CBeWQshBn,
validTime=120,m userName=c.mammoli,
userId=d69d8d20-68b7-4fed-9c08-5c2ecb257583), log id: 4532bed0
2015-07-27 12:24:26,606 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand]
(ajp--127.0.0.1-8702-3) [515006c1] FINISH, SetVmTicketVDSCommand, log
id: 4532bed0
2015-07-27 12:24:26,628 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(ajp--127.0.0.1-8702-3) [515006c1] Correlation ID: 515006c1, Call Stack:
null, Custom Event ID: -1, Message: user c.mammoli at apra.it initiated
console session for VM TestPoolAuto-1
2015-07-27 12:24:26,681 WARN
[org.ovirt.engine.core.dal.job.ExecutionMessageDirector]
(ajp--127.0.0.1-8702-7) [532d1bbb] The message key VmLogon is missing
from bundles/ExecutionMessages
2015-07-27 12:24:26,789 INFO [org.ovirt.engine.core.bll.VmLogonCommand]
(ajp--127.0.0.1-8702-7) [532d1bbb] Running command: VmLogonCommand
internal: false. Entities affected : ID:
bc628809-6018-48d9-a0d0-793d8e2ae60b Type: VMAction group CONNECT_TO_VM
with role type USER
2015-07-27 12:24:26,794 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.VmLogonVDSCommand]
(ajp--127.0.0.1-8702-7) [532d1bbb] START, VmLogonVDSCommand(HostName =
kvm01, HostId = d2b7c9d1-6e5b-4222-a5ee-9a37ba8abcfe,
vmId=bc628809-6018-48d9-a0d0-793d8e2ae60b, domain=apra.it,
password=******, userName=c.mammoli at apra.it), log id: 3915ba19
2015-07-27 12:24:26,819 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.VmLogonVDSCommand]
(ajp--127.0.0.1-8702-7) [532d1bbb] FINISH, VmLogonVDSCommand, log id:
3915ba19
--
More information about the Users
mailing list