3:09 a.m.
Hi again
I created an automatic pool with a Win 7 template and Template sub
version set to "latest"
In the Pool I configured sysprep with domain membership and such
I started the vms from the admin portal to make them sysprep then shutdown
Then I created another template and set it to be a subversion of the
above template.
The vms in the pool get rebased but If I go in the pool options the
sysprep configuration is gone (unflagged) and there is no way to
re-enable it.
If I login as a user and start one of the vms in the pool it does not
sysprep and does not join the vm to the domain...
What am I missing??
1:18 a.m.
New subject: Pool with "latest template" does not sysprep when rebased
----- Original Message -----
From: "Cristian Mammoli" <c.mammoli(a)apra.it>
To: users(a)ovirt.org
Sent: Friday, July 24, 2015 11:09:55 AM
Subject: [ovirt-users] Pool with "latest template" does not sysprep
when rebased
Hi again
I created an automatic pool with a Win 7 template and Template sub
version set to "latest"
In the Pool I configured sysprep with domain membership and such
I started the vms from the admin portal to make them sysprep then shutdown
Then I created another template and set it to be a subversion of the
above template.
The vms in the pool get rebased but If I go in the pool options the
sysprep configuration is gone (unflagged) and there is no way to
re-enable it.
If I login as a user and start one of the vms in the pool it does not
sysprep and does not join the vm to the domain...
What am I missing??
the new template has the sysprep fields filled correctly?
do you see any error in the log?
can you attach the engine.log for the time user takes vm from pool?
please note that if vm was started from admin before it was started from user portal,
it was started in stateful mode and sysprep will be marked as done, and will not happen
again..
_______________________________________________
Users mailing list
Users(a)ovirt.org
http://lists.ovirt.org/mailman/listinfo/users
2:52 a.m.
the new template has the sysprep fields filled correctly?
do you see any error in the log?
can you attach the engine.log for the time user takes vm from pool?
please note that if vm was started from admin before it was started from user portal,
it was started in stateful mode and sysprep will be marked as done, and will not happen
again..
Of course the vm was started as admin to make the first sysprep run.
Otherwise it would sysprep at every boot, but If I rebase the vm it
needs to sysprep once again! For the logs I'll provide them asap
5:28 a.m.
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(a)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@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@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@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(a)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(a)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
--
5:41 a.m.
----- Original Message -----
From: "Cristian Mammoli" <c.mammoli(a)apra.it>
To: "Omer Frenkel" <ofrenkel(a)redhat.com>
Cc: users(a)ovirt.org
Sent: Monday, July 27, 2015 1:28:42 PM
Subject: Re: [ovirt-users] Pool with "latest template" does not sysprep when
rebased
> 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(a)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@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@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
warning for missing user name (i think its needed to join the domain)
also the default is empty (SysPrepDefaultUser in engine config)
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,
i can see that sysprep file is sent to the vm (see below)
but it looks like the user that is set for this domain is missing (see above)
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@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(a)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(a)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
--
3198
days inactive
3201
days old
4 comments
2 participants
participants (2)
-
Cristian Mammoli -
Omer Frenkel