Attachment 936543 Details for Bug 1109880 – extract of engine log

extract of engine log

extract_engine.log (text/plain), 81.74 KB, created by exploit on 2014-09-11 13:19:47 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: exploit

Created: 2014-09-11 13:19:47 UTC

Size: 81.74 KB

patch

obsolete

>2014-09-11 14:38:18,594 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp--127.0.0.1-8702-7) [3c03ed2] Lock Acquired to object EngineLock [exclusiveLocks= key: testcentos7gui value: VM_NAME
>, sharedLocks= ]
>2014-09-11 14:38:18,681 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp--127.0.0.1-8702-7) [3c03ed2] Running command: AddVmFromScratchCommand internal: false. Entities affected :  ID: d4969e12-eec6-47d9-9aa6-06c8b47fab84 Type: VdsGroups
>2014-09-11 14:38:18,901 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) [3c03ed2] Correlation ID: 3c03ed2, Job ID: c3baedff-63ec-49b1-ad3b-ec7b05f775cd, Call Stack: null, Custom Event ID: -1, Message: VM testcentos7gui was created by sblanchet.
>2014-09-11 14:38:18,908 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp--127.0.0.1-8702-7) [3c03ed2] Lock freed to object EngineLock [exclusiveLocks= key: testcentos7gui value: VM_NAME
>, sharedLocks= ]
>2014-09-11 14:38:20,374 INFO  [org.ovirt.engine.core.bll.network.vm.AddVmInterfaceCommand] (ajp--127.0.0.1-8702-5) [251ca2e9] Running command: AddVmInterfaceCommand internal: false. Entities affected :  ID: 5974b4fc-d37d-49a1-a102-6ab68775ce07 Type: VM,  ID: 00000010-0010-0010-0010-000000000010 Type: VnicProfile
>2014-09-11 14:38:20,441 INFO  [org.ovirt.engine.core.bll.network.vm.ActivateDeactivateVmNicCommand] (ajp--127.0.0.1-8702-5) [540b7c8e] Running command: ActivateDeactivateVmNicCommand internal: true. Entities affected :  ID: 5974b4fc-d37d-49a1-a102-6ab68775ce07 Type: VM
>2014-09-11 14:38:20,473 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-5) [540b7c8e] Correlation ID: 540b7c8e, Call Stack: null, Custom Event ID: -1, Message: Network Interface nic1 (VirtIO) was plugged to VM testcentos7gui. (User: sblanchet)
>2014-09-11 14:38:20,483 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-5) [540b7c8e] Correlation ID: 251ca2e9, Call Stack: null, Custom Event ID: -1, Message: Interface nic1 (VirtIO) was added to VM testcentos7gui. (User: sblanchet)
>2014-09-11 14:38:20,519 INFO  [org.ovirt.engine.core.bll.network.vm.ReorderVmNicsCommand] (ajp--127.0.0.1-8702-6) [5813b333] Running command: ReorderVmNicsCommand internal: false. Entities affected :  ID: 5974b4fc-d37d-49a1-a102-6ab68775ce07 Type: VM
>2014-09-11 14:38:21,124 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-93) [2cdd5adf] START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 778932da
>2014-09-11 14:38:21,158 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-93) [2cdd5adf] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 778932da
>2014-09-11 14:38:21,379 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-93) [2cdd5adf] START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 209f205f
>2014-09-11 14:38:21,395 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-93) [2cdd5adf] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 209f205f
>2014-09-11 14:38:21,401 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-93) [2cdd5adf] START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 3aeae11a
>2014-09-11 14:38:21,421 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-93) [2cdd5adf] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 3aeae11a
>2014-09-11 14:38:26,430 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-89) [76e724b3] START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 4934da59
>2014-09-11 14:38:26,499 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-89) [76e724b3] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 4934da59
>2014-09-11 14:38:26,724 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-89) [76e724b3] START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 46815b5d
>2014-09-11 14:38:26,740 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-89) [76e724b3] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 46815b5d
>2014-09-11 14:38:26,748 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-89) [76e724b3] START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 7da2ad0c
>2014-09-11 14:38:26,790 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-89) [76e724b3] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 7da2ad0c
>2014-09-11 14:38:31,802 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-76) START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 5d1b219f
>2014-09-11 14:38:31,891 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-76) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 5d1b219f
>2014-09-11 14:38:32,108 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-76) START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 2d54bc00
>2014-09-11 14:38:32,126 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-76) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 2d54bc00
>2014-09-11 14:38:32,133 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-76) START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 3139f1d2
>2014-09-11 14:38:32,153 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-76) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 3139f1d2
>2014-09-11 14:38:36,510 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (ajp--127.0.0.1-8702-13) [6f4c4a78] Lock Acquired to object EngineLock [exclusiveLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM_DISK_BOOT
>, sharedLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM
>]
>2014-09-11 14:38:36,581 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (ajp--127.0.0.1-8702-13) [6f4c4a78] Running command: AddDiskCommand internal: false. Entities affected :  ID: 5974b4fc-d37d-49a1-a102-6ab68775ce07 Type: VM,  ID: 5ef8572c-0ab5-4491-994a-e4c30230a525 Type: Storage
>2014-09-11 14:38:36,633 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (ajp--127.0.0.1-8702-13) [11b9de33] Running command: AddImageFromScratchCommand internal: true. Entities affected :  ID: 5ef8572c-0ab5-4491-994a-e4c30230a525 Type: Storage
>2014-09-11 14:38:36,658 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (ajp--127.0.0.1-8702-13) [11b9de33] Lock freed to object EngineLock [exclusiveLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM_DISK_BOOT
>, sharedLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM
>]
>2014-09-11 14:38:36,659 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp--127.0.0.1-8702-13) [11b9de33] START, CreateImageVDSCommand( storagePoolId = 48ca3019-9dbf-4ef3-98e9-08105d396350, ignoreFailoverLimit = false, storageDomainId = 5ef8572c-0ab5-4491-994a-e4c30230a525, imageGroupId = b24d1090-4c9b-4f74-9be9-a9f1950c201b, imageSizeInBytes = 21474836480, volumeFormat = RAW, newImageId = 119bed1b-3b2e-4790-ae75-7eed3d5e386c, newImageDescription = ), log id: 663a7576
>2014-09-11 14:38:36,661 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp--127.0.0.1-8702-13) [11b9de33] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID
>2014-09-11 14:38:36,697 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp--127.0.0.1-8702-13) [11b9de33] FINISH, CreateImageVDSCommand, return: 119bed1b-3b2e-4790-ae75-7eed3d5e386c, log id: 663a7576
>2014-09-11 14:38:36,709 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp--127.0.0.1-8702-13) [11b9de33] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 8c0e1dd7-0195-4d4c-8b64-2f1c0419e3da
>2014-09-11 14:38:36,710 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp--127.0.0.1-8702-13) [11b9de33] CommandMultiAsyncTasks::AttachTask: Attaching task 64e8ea11-0520-4efa-ad69-ed270647c2d2 to command 8c0e1dd7-0195-4d4c-8b64-2f1c0419e3da.
>2014-09-11 14:38:36,749 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp--127.0.0.1-8702-13) [11b9de33] Adding task 64e8ea11-0520-4efa-ad69-ed270647c2d2 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2014-09-11 14:38:36,814 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-13) [11b9de33] Correlation ID: 6f4c4a78, Job ID: c507cab9-0b00-4b57-ab60-3ceb4a686b87, Call Stack: null, Custom Event ID: -1, Message: Add-Disk operation of testcentos7gui_Disk1 was initiated on VM testcentos7gui by sblanchet.
>2014-09-11 14:38:36,816 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp--127.0.0.1-8702-13) [11b9de33] BaseAsyncTask::startPollingTask: Starting to poll task 64e8ea11-0520-4efa-ad69-ed270647c2d2.
>2014-09-11 14:38:37,166 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-94) [1cdec8ff] START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 7c067e6b
>2014-09-11 14:38:37,195 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-94) [1cdec8ff] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 7c067e6b
>2014-09-11 14:38:37,444 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-94) [1cdec8ff] START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 3d5667b6
>2014-09-11 14:38:37,469 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-94) [1cdec8ff] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 3d5667b6
>2014-09-11 14:38:37,475 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-94) [1cdec8ff] START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 311132d6
>2014-09-11 14:38:37,495 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-94) [1cdec8ff] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 311132d6
>2014-09-11 14:38:42,503 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-6) START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 7d37094a
>2014-09-11 14:38:42,547 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-6) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 7d37094a
>2014-09-11 14:38:42,561 INFO  [org.ovirt.engine.core.bll.scheduling.PolicyUnitImpl] (DefaultQuartzScheduler_Worker-15) [3008836c] There is no over-utilized host in cluster Cluster1
>2014-09-11 14:38:42,765 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-6) START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 22f3d33e
>2014-09-11 14:38:42,788 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-6) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 22f3d33e
>2014-09-11 14:38:42,794 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-6) START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 48c3370f
>2014-09-11 14:38:42,813 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-6) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 48c3370f
>2014-09-11 14:38:44,266 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler_Worker-18) [163eee19] START, GlusterTasksListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 44bb59e9
>2014-09-11 14:38:44,320 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler_Worker-18) [163eee19] FINISH, GlusterTasksListVDSCommand, return: [], log id: 44bb59e9
>2014-09-11 14:38:44,450 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler_Worker-18) [163eee19] START, GlusterTasksListVDSCommand(HostName = ouragan, HostId = d8e5bf92-b56c-4dee-81f0-bc61b86e6541), log id: 24bbded4
>2014-09-11 14:38:44,504 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler_Worker-18) [163eee19] FINISH, GlusterTasksListVDSCommand, return: [], log id: 24bbded4
>2014-09-11 14:38:44,508 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler_Worker-18) [163eee19] START, GlusterTasksListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 7fc01a7
>2014-09-11 14:38:44,535 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler_Worker-18) [163eee19] FINISH, GlusterTasksListVDSCommand, return: [], log id: 7fc01a7
>2014-09-11 14:38:46,656 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-21) [5a79899e] Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
>2014-09-11 14:38:46,664 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-21) [5a79899e] SPMAsyncTask::PollTask: Polling task 64e8ea11-0520-4efa-ad69-ed270647c2d2 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2014-09-11 14:38:46,677 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-21) [5a79899e] BaseAsyncTask::OnTaskEndSuccess: Task 64e8ea11-0520-4efa-ad69-ed270647c2d2 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2014-09-11 14:38:46,678 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-21) [5a79899e] CommandAsyncTask::EndActionIfNecessary: All tasks of command 8c0e1dd7-0195-4d4c-8b64-2f1c0419e3da has ended -> executing endAction
>2014-09-11 14:38:46,679 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-21) [5a79899e] CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: 8c0e1dd7-0195-4d4c-8b64-2f1c0419e3da): calling endAction .
>2014-09-11 14:38:46,681 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-48) [5a79899e] CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction AddDisk, executionIndex: 0
>2014-09-11 14:38:46,690 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (org.ovirt.thread.pool-6-thread-48) [6f4c4a78] Ending command successfully: org.ovirt.engine.core.bll.AddDiskCommand
>2014-09-11 14:38:46,701 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (org.ovirt.thread.pool-6-thread-48) [11b9de33] Ending command successfully: org.ovirt.engine.core.bll.AddImageFromScratchCommand
>2014-09-11 14:38:46,713 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-6-thread-48) [11b9de33] START, GetImageInfoVDSCommand( storagePoolId = 48ca3019-9dbf-4ef3-98e9-08105d396350, ignoreFailoverLimit = false, storageDomainId = 5ef8572c-0ab5-4491-994a-e4c30230a525, imageGroupId = b24d1090-4c9b-4f74-9be9-a9f1950c201b, imageId = 119bed1b-3b2e-4790-ae75-7eed3d5e386c), log id: acc9d4d
>2014-09-11 14:38:46,743 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-6-thread-48) [11b9de33] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@a96114c6, log id: acc9d4d
>2014-09-11 14:38:46,788 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-48) Correlation ID: 6f4c4a78, Job ID: c507cab9-0b00-4b57-ab60-3ceb4a686b87, Call Stack: null, Custom Event ID: -1, Message: The disk testcentos7gui_Disk1 was successfully added to VM testcentos7gui.
>2014-09-11 14:38:46,790 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-48) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk completed, handling the result.
>2014-09-11 14:38:46,791 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-48) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk succeeded, clearing tasks.
>2014-09-11 14:38:46,797 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-6-thread-48) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 64e8ea11-0520-4efa-ad69-ed270647c2d2
>2014-09-11 14:38:46,798 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-48) START, SPMClearTaskVDSCommand( storagePoolId = 48ca3019-9dbf-4ef3-98e9-08105d396350, ignoreFailoverLimit = false, taskId = 64e8ea11-0520-4efa-ad69-ed270647c2d2), log id: 13590cb7
>2014-09-11 14:38:46,829 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-48) START, HSMClearTaskVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198, taskId=64e8ea11-0520-4efa-ad69-ed270647c2d2), log id: 3b51cc4d
>2014-09-11 14:38:46,839 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-48) FINISH, HSMClearTaskVDSCommand, log id: 3b51cc4d
>2014-09-11 14:38:46,839 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-48) FINISH, SPMClearTaskVDSCommand, log id: 13590cb7
>2014-09-11 14:38:46,845 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-6-thread-48) BaseAsyncTask::RemoveTaskFromDB: Removed task 64e8ea11-0520-4efa-ad69-ed270647c2d2 from DataBase
>2014-09-11 14:38:46,846 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-48) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 8c0e1dd7-0195-4d4c-8b64-2f1c0419e3da
>2014-09-11 14:38:47,827 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-100) [41f921dc] START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 192bb642
>2014-09-11 14:38:47,915 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-100) [41f921dc] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 192bb642
>2014-09-11 14:38:48,129 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-100) [41f921dc] START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 2e9d8df5
>2014-09-11 14:38:48,149 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-100) [41f921dc] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 2e9d8df5
>2014-09-11 14:38:48,155 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-100) [41f921dc] START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 3ce23ac5
>2014-09-11 14:38:48,181 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-100) [41f921dc] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 3ce23ac5
>2014-09-11 14:38:53,190 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-5) [36ae313c] START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 78d29963
>2014-09-11 14:38:53,228 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-5) [36ae313c] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 78d29963
>2014-09-11 14:38:53,445 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-5) [36ae313c] START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 7ed257a6
>2014-09-11 14:38:53,467 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-5) [36ae313c] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 7ed257a6
>2014-09-11 14:38:53,473 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-5) [36ae313c] START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 21e20292
>2014-09-11 14:38:53,512 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-5) [36ae313c] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 21e20292
>2014-09-11 14:38:58,524 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-52) [386ddf2f] START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 6e475a29
>2014-09-11 14:38:58,583 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-52) [386ddf2f] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 6e475a29
>2014-09-11 14:38:58,807 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-52) [386ddf2f] START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 69e476e8
>2014-09-11 14:38:58,824 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-52) [386ddf2f] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 69e476e8
>2014-09-11 14:38:58,830 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-52) [386ddf2f] START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 139388af
>2014-09-11 14:38:58,852 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-52) [386ddf2f] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 139388af
>2014-09-11 14:39:03,861 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-98) [7b7a44e4] START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 2c7f970b
>2014-09-11 14:39:03,939 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-98) [7b7a44e4] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 2c7f970b
>2014-09-11 14:39:04,157 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-98) [7b7a44e4] START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 1e26e4ee
>2014-09-11 14:39:04,176 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-98) [7b7a44e4] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 1e26e4ee
>2014-09-11 14:39:04,182 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-98) [7b7a44e4] START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 331aa304
>2014-09-11 14:39:04,249 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-98) [7b7a44e4] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 331aa304
>2014-09-11 14:39:04,973 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-12) [5953ebf8] Lock Acquired to object EngineLock [exclusiveLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM
>, sharedLocks= ]
>2014-09-11 14:39:05,004 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-12) [5953ebf8] START, IsVmDuringInitiatingVDSCommand( vmId = 5974b4fc-d37d-49a1-a102-6ab68775ce07), log id: 327d54e3
>2014-09-11 14:39:05,005 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-12) [5953ebf8] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 327d54e3
>2014-09-11 14:39:05,083 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] Running command: RunVmCommand internal: false. Entities affected :  ID: 5974b4fc-d37d-49a1-a102-6ab68775ce07 Type: VM
>2014-09-11 14:39:05,173 INFO  [org.ovirt.engine.core.bll.scheduling.policyunits.HaReservationWeightPolicyUnit] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] Started HA reservation scoring method
>2014-09-11 14:39:05,321 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] Running VM with attached cd CentOS-7.0-1406-x86_64-DVD.iso
>2014-09-11 14:39:05,324 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] START, IsoPrefixVDSCommand(HostName = eurus, HostId = a9a66087-f7c1-4953-b55e-17feb784438e, storagePoolId=48ca3019-9dbf-4ef3-98e9-08105d396350), log id: 3bf457b5
>2014-09-11 14:39:05,326 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/hupe.abes.fr:_ovirt__iso/cdc09bbb-fd7a-4a48-8b6a-cd7bccbecc3d/images/11111111-1111-1111-1111-111111111111, log id: 3bf457b5
>2014-09-11 14:39:05,345 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] START, CreateVmVDSCommand(HostName = eurus, HostId = a9a66087-f7c1-4953-b55e-17feb784438e, vmId=5974b4fc-d37d-49a1-a102-6ab68775ce07, vm=VM [testcentos7gui]), log id: 23ad538c
>2014-09-11 14:39:05,357 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] START, CreateVDSCommand(HostName = eurus, HostId = a9a66087-f7c1-4953-b55e-17feb784438e, vmId=5974b4fc-d37d-49a1-a102-6ab68775ce07, vm=VM [testcentos7gui]), log id: 5b88a924
>2014-09-11 14:39:05,458 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=1024,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=rhel6.5.0,keyboardLayout=en-us,memGuaranteedSize=682,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,maxVCpus=160,timeOffset=0,transparentHugePages=true,vmId=5974b4fc-d37d-49a1-a102-6ab68775ce07,devices=[{specParams={vram=32768, heads=1}, device=qxl, type=video, deviceId=c356c443-83e1-4a95-b3c4-743035fa075a}, {shared=false, iface=ide, index=2, specParams={path=CentOS-7.0-1406-x86_64-DVD.iso}, path=/rhev/data-center/mnt/hupe.abes.fr:_ovirt__iso/cdc09bbb-fd7a-4a48-8b6a-cd7bccbecc3d/images/11111111-1111-1111-1111-111111111111/CentOS-7.0-1406-x86_64-DVD.iso, device=cdrom, type=disk, readonly=true, deviceId=61dd30c1-aba1-4769-8fee-9e42d1a6a9c9}, {shared=false, iface=ide, index=2, specParams={path=CentOS-7.0-1406-x86_64-DVD.iso}, path=/rhev/data-center/mnt/hupe.abes.fr:_ovirt__iso/cdc09bbb-fd7a-4a48-8b6a-cd7bccbecc3d/images/11111111-1111-1111-1111-111111111111/CentOS-7.0-1406-x86_64-DVD.iso, device=cdrom, type=disk, readonly=true, deviceId=65b847e4-74ec-409c-b927-85f2447f3650}, {shared=false, volumeID=119bed1b-3b2e-4790-ae75-7eed3d5e386c, index=0, iface=virtio, imageID=b24d1090-4c9b-4f74-9be9-a9f1950c201b, domainID=5ef8572c-0ab5-4491-994a-e4c30230a525, specParams={}, optional=false, propagateErrors=off, device=disk, poolID=48ca3019-9dbf-4ef3-98e9-08105d396350, format=raw, type=disk, readonly=false, deviceId=b24d1090-4c9b-4f74-9be9-a9f1950c201b}, {nicModel=pv, specParams={outbound={}, inbound={}}, macAddr=00:1a:4a:22:1e:59, device=bridge, linkActive=true, type=interface, filter=vdsm-no-mac-spoofing, network=brv11, deviceId=0d12b244-baff-4bad-8f7d-7e8b184e20a5}, {specParams={model=virtio}, device=memballoon, type=balloon, deviceId=5ef09f26-7058-4b30-93ce-1468479524b0}, {index=0, model=virtio-scsi, specParams={}, device=scsi, type=controller, deviceId=5a93e191-f4b7-475a-98a3-e42d046dfbe1}],acpiEnable=true,vmName=testcentos7gui,cpuType=Westmere,custom={}
>2014-09-11 14:39:05,467 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] FINISH, CreateVDSCommand, log id: 5b88a924
>2014-09-11 14:39:05,474 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 23ad538c
>2014-09-11 14:39:05,475 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] Lock freed to object EngineLock [exclusiveLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM
>, sharedLocks= ]
>2014-09-11 14:39:05,481 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] Correlation ID: 5953ebf8, Job ID: 8805ac08-bb88-4240-912f-dde1c7a38709, Call Stack: null, Custom Event ID: -1, Message: VM testcentos7gui was started by sblanchet (Host: eurus).
>2014-09-11 14:39:09,260 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-19) START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: b45c4da
>2014-09-11 14:39:09,326 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-19) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: b45c4da
>2014-09-11 14:39:09,550 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-19) START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 23428acf
>2014-09-11 14:39:09,568 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-19) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 23428acf
>2014-09-11 14:39:09,574 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-19) START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 36a6359c
>2014-09-11 14:39:09,603 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-19) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 36a6359c
>2014-09-11 14:39:10,462 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-43) START, DestroyVDSCommand(HostName = eurus, HostId = a9a66087-f7c1-4953-b55e-17feb784438e, vmId=5974b4fc-d37d-49a1-a102-6ab68775ce07, force=false, secondsToWait=0, gracefully=false), log id: 18e9eb45
>2014-09-11 14:39:11,664 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-43) FINISH, DestroyVDSCommand, log id: 18e9eb45
>2014-09-11 14:39:11,731 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-43) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VM testcentos7gui is down. Exit message: internal error process exited while connecting to monitor: qemu-kvm: -drive file=/rhev/data-center/mnt/hupe.abes.fr:_ovirt__iso/cdc09bbb-fd7a-4a48-8b6a-cd7bccbecc3d/images/11111111-1111-1111-1111-111111111111/CentOS-7.0-1406-x86_64-DVD.iso,if=none,media=cdrom,id=drive-ide0-1-0,readonly=on,format=raw,serial=: Duplicate ID 'drive-ide0-1-0' for drive
>.
>2014-09-11 14:39:11,734 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-43) Running on vds during rerun failed vm: null
>2014-09-11 14:39:11,736 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-43) VM testcentos7gui (5974b4fc-d37d-49a1-a102-6ab68775ce07) is running in db and not running in VDS eurus
>2014-09-11 14:39:11,737 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-43) START, FullListVdsCommand(HostName = eurus, HostId = a9a66087-f7c1-4953-b55e-17feb784438e, vds=Host[eurus,a9a66087-f7c1-4953-b55e-17feb784438e], vmIds=[5974b4fc-d37d-49a1-a102-6ab68775ce07]), log id: 1fe9f9f1
>2014-09-11 14:39:11,744 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-43) FINISH, FullListVdsCommand, return: [], log id: 1fe9f9f1
>2014-09-11 14:39:11,749 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-43) Rerun vm 5974b4fc-d37d-49a1-a102-6ab68775ce07. Called from vds eurus
>2014-09-11 14:39:11,756 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-45) Correlation ID: 5953ebf8, Job ID: 8805ac08-bb88-4240-912f-dde1c7a38709, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM testcentos7gui on Host eurus.
>2014-09-11 14:39:11,775 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-45) Lock Acquired to object EngineLock [exclusiveLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM
>, sharedLocks= ]
>2014-09-11 14:39:11,805 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-6-thread-45) START, IsVmDuringInitiatingVDSCommand( vmId = 5974b4fc-d37d-49a1-a102-6ab68775ce07), log id: 28a0c083
>2014-09-11 14:39:11,806 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-6-thread-45) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 28a0c083
>2014-09-11 14:39:11,856 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-45) Running command: RunVmCommand internal: false. Entities affected :  ID: 5974b4fc-d37d-49a1-a102-6ab68775ce07 Type: VM
>2014-09-11 14:39:11,904 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-45) Running VM with attached cd CentOS-7.0-1406-x86_64-DVD.iso
>2014-09-11 14:39:11,907 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (org.ovirt.thread.pool-6-thread-45) START, IsoPrefixVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556, storagePoolId=48ca3019-9dbf-4ef3-98e9-08105d396350), log id: 4b6cee3
>2014-09-11 14:39:11,908 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (org.ovirt.thread.pool-6-thread-45) FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/hupe.abes.fr:_ovirt__iso/cdc09bbb-fd7a-4a48-8b6a-cd7bccbecc3d/images/11111111-1111-1111-1111-111111111111, log id: 4b6cee3
>2014-09-11 14:39:11,920 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-6-thread-45) START, CreateVmVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556, vmId=5974b4fc-d37d-49a1-a102-6ab68775ce07, vm=VM [testcentos7gui]), log id: 18e6eaf9
>2014-09-11 14:39:11,924 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-45) START, CreateVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556, vmId=5974b4fc-d37d-49a1-a102-6ab68775ce07, vm=VM [testcentos7gui]), log id: 76eb280b
>2014-09-11 14:39:12,056 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-45) org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=1024,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=rhel6.5.0,keyboardLayout=en-us,memGuaranteedSize=682,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,maxVCpus=160,timeOffset=0,transparentHugePages=true,vmId=5974b4fc-d37d-49a1-a102-6ab68775ce07,devices=[{specParams={vram=32768, heads=1}, device=qxl, type=video, deviceId=c356c443-83e1-4a95-b3c4-743035fa075a}, {shared=false, iface=ide, index=2, specParams={path=CentOS-7.0-1406-x86_64-DVD.iso}, path=/rhev/data-center/mnt/hupe.abes.fr:_ovirt__iso/cdc09bbb-fd7a-4a48-8b6a-cd7bccbecc3d/images/11111111-1111-1111-1111-111111111111/CentOS-7.0-1406-x86_64-DVD.iso, device=cdrom, type=disk, readonly=true, deviceId=61dd30c1-aba1-4769-8fee-9e42d1a6a9c9}, {shared=false, iface=ide, index=2, specParams={path=CentOS-7.0-1406-x86_64-DVD.iso}, path=/rhev/data-center/mnt/hupe.abes.fr:_ovirt__iso/cdc09bbb-fd7a-4a48-8b6a-cd7bccbecc3d/images/11111111-1111-1111-1111-111111111111/CentOS-7.0-1406-x86_64-DVD.iso, device=cdrom, type=disk, readonly=true, deviceId=65b847e4-74ec-409c-b927-85f2447f3650}, {shared=false, volumeID=119bed1b-3b2e-4790-ae75-7eed3d5e386c, index=0, iface=virtio, imageID=b24d1090-4c9b-4f74-9be9-a9f1950c201b, domainID=5ef8572c-0ab5-4491-994a-e4c30230a525, specParams={}, optional=false, propagateErrors=off, device=disk, poolID=48ca3019-9dbf-4ef3-98e9-08105d396350, format=raw, type=disk, readonly=false, deviceId=b24d1090-4c9b-4f74-9be9-a9f1950c201b}, {nicModel=pv, specParams={outbound={}, inbound={}}, macAddr=00:1a:4a:22:1e:59, device=bridge, linkActive=true, type=interface, filter=vdsm-no-mac-spoofing, network=brv11, deviceId=0d12b244-baff-4bad-8f7d-7e8b184e20a5}, {specParams={model=virtio}, device=memballoon, type=balloon, deviceId=5ef09f26-7058-4b30-93ce-1468479524b0}, {index=0, model=virtio-scsi, specParams={}, device=scsi, type=controller, deviceId=5a93e191-f4b7-475a-98a3-e42d046dfbe1}],acpiEnable=true,vmName=testcentos7gui,cpuType=Westmere,custom={}
>2014-09-11 14:39:12,065 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-45) FINISH, CreateVDSCommand, log id: 76eb280b
>2014-09-11 14:39:12,070 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-6-thread-45) FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 18e6eaf9
>2014-09-11 14:39:12,071 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-45) Lock freed to object EngineLock [exclusiveLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM
>, sharedLocks= ]
>2014-09-11 14:39:12,078 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-45) Correlation ID: 5953ebf8, Job ID: 8805ac08-bb88-4240-912f-dde1c7a38709, Call Stack: null, Custom Event ID: -1, Message: VM testcentos7gui was started by sblanchet (Host: haboob).
>2014-09-11 14:39:14,612 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-49) [6c4e2815] START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 5b3f476d014-09-11 14:38:18,594 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp--127.0.0.1-8702-7) [3c03ed2] Lock Acquired to object EngineLock [exclusiveLocks= key: testcentos7gui value: VM_NAME
>, sharedLocks= ]
>2014-09-11 14:38:18,681 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp--127.0.0.1-8702-7) [3c03ed2] Running command: AddVmFromScratchCommand internal: false. Entities affected :  ID: d4969e12-eec6-47d9-9aa6-06c8b47fab84 Type: VdsGroups
>2014-09-11 14:38:18,901 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) [3c03ed2] Correlation ID: 3c03ed2, Job ID: c3baedff-63ec-49b1-ad3b-ec7b05f775cd, Call Stack: null, Custom Event ID: -1, Message: VM testcentos7gui was created by sblanchet.
>2014-09-11 14:38:18,908 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp--127.0.0.1-8702-7) [3c03ed2] Lock freed to object EngineLock [exclusiveLocks= key: testcentos7gui value: VM_NAME
>, sharedLocks= ]
>2014-09-11 14:38:20,374 INFO  [org.ovirt.engine.core.bll.network.vm.AddVmInterfaceCommand] (ajp--127.0.0.1-8702-5) [251ca2e9] Running command: AddVmInterfaceCommand internal: false. Entities affected :  ID: 5974b4fc-d37d-49a1-a102-6ab68775ce07 Type: VM,  ID: 00000010-0010-0010-0010-000000000010 Type: VnicProfile
>2014-09-11 14:38:20,441 INFO  [org.ovirt.engine.core.bll.network.vm.ActivateDeactivateVmNicCommand] (ajp--127.0.0.1-8702-5) [540b7c8e] Running command: ActivateDeactivateVmNicCommand internal: true. Entities affected :  ID: 5974b4fc-d37d-49a1-a102-6ab68775ce07 Type: VM
>2014-09-11 14:38:20,473 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-5) [540b7c8e] Correlation ID: 540b7c8e, Call Stack: null, Custom Event ID: -1, Message: Network Interface nic1 (VirtIO) was plugged to VM testcentos7gui. (User: sblanchet)
>2014-09-11 14:38:20,483 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-5) [540b7c8e] Correlation ID: 251ca2e9, Call Stack: null, Custom Event ID: -1, Message: Interface nic1 (VirtIO) was added to VM testcentos7gui. (User: sblanchet)
>2014-09-11 14:38:20,519 INFO  [org.ovirt.engine.core.bll.network.vm.ReorderVmNicsCommand] (ajp--127.0.0.1-8702-6) [5813b333] Running command: ReorderVmNicsCommand internal: false. Entities affected :  ID: 5974b4fc-d37d-49a1-a102-6ab68775ce07 Type: VM
>2014-09-11 14:38:21,124 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-93) [2cdd5adf] START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 778932da
>2014-09-11 14:38:21,158 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-93) [2cdd5adf] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 778932da
>2014-09-11 14:38:21,379 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-93) [2cdd5adf] START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 209f205f
>2014-09-11 14:38:21,395 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-93) [2cdd5adf] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 209f205f
>2014-09-11 14:38:21,401 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-93) [2cdd5adf] START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 3aeae11a
>2014-09-11 14:38:21,421 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-93) [2cdd5adf] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 3aeae11a
>2014-09-11 14:38:26,430 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-89) [76e724b3] START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 4934da59
>2014-09-11 14:38:26,499 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-89) [76e724b3] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 4934da59
>2014-09-11 14:38:26,724 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-89) [76e724b3] START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 46815b5d
>2014-09-11 14:38:26,740 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-89) [76e724b3] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 46815b5d
>2014-09-11 14:38:26,748 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-89) [76e724b3] START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 7da2ad0c
>2014-09-11 14:38:26,790 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-89) [76e724b3] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 7da2ad0c
>2014-09-11 14:38:31,802 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-76) START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 5d1b219f
>2014-09-11 14:38:31,891 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-76) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 5d1b219f
>2014-09-11 14:38:32,108 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-76) START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 2d54bc00
>2014-09-11 14:38:32,126 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-76) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 2d54bc00
>2014-09-11 14:38:32,133 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-76) START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 3139f1d2
>2014-09-11 14:38:32,153 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-76) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 3139f1d2
>2014-09-11 14:38:36,510 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (ajp--127.0.0.1-8702-13) [6f4c4a78] Lock Acquired to object EngineLock [exclusiveLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM_DISK_BOOT
>, sharedLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM
>]
>2014-09-11 14:38:36,581 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (ajp--127.0.0.1-8702-13) [6f4c4a78] Running command: AddDiskCommand internal: false. Entities affected :  ID: 5974b4fc-d37d-49a1-a102-6ab68775ce07 Type: VM,  ID: 5ef8572c-0ab5-4491-994a-e4c30230a525 Type: Storage
>2014-09-11 14:38:36,633 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (ajp--127.0.0.1-8702-13) [11b9de33] Running command: AddImageFromScratchCommand internal: true. Entities affected :  ID: 5ef8572c-0ab5-4491-994a-e4c30230a525 Type: Storage
>2014-09-11 14:38:36,658 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (ajp--127.0.0.1-8702-13) [11b9de33] Lock freed to object EngineLock [exclusiveLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM_DISK_BOOT
>, sharedLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM
>]
>2014-09-11 14:38:36,659 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp--127.0.0.1-8702-13) [11b9de33] START, CreateImageVDSCommand( storagePoolId = 48ca3019-9dbf-4ef3-98e9-08105d396350, ignoreFailoverLimit = false, storageDomainId = 5ef8572c-0ab5-4491-994a-e4c30230a525, imageGroupId = b24d1090-4c9b-4f74-9be9-a9f1950c201b, imageSizeInBytes = 21474836480, volumeFormat = RAW, newImageId = 119bed1b-3b2e-4790-ae75-7eed3d5e386c, newImageDescription = ), log id: 663a7576
>2014-09-11 14:38:36,661 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp--127.0.0.1-8702-13) [11b9de33] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID
>2014-09-11 14:38:36,697 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp--127.0.0.1-8702-13) [11b9de33] FINISH, CreateImageVDSCommand, return: 119bed1b-3b2e-4790-ae75-7eed3d5e386c, log id: 663a7576
>2014-09-11 14:38:36,709 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp--127.0.0.1-8702-13) [11b9de33] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 8c0e1dd7-0195-4d4c-8b64-2f1c0419e3da
>2014-09-11 14:38:36,710 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp--127.0.0.1-8702-13) [11b9de33] CommandMultiAsyncTasks::AttachTask: Attaching task 64e8ea11-0520-4efa-ad69-ed270647c2d2 to command 8c0e1dd7-0195-4d4c-8b64-2f1c0419e3da.
>2014-09-11 14:38:36,749 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp--127.0.0.1-8702-13) [11b9de33] Adding task 64e8ea11-0520-4efa-ad69-ed270647c2d2 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2014-09-11 14:38:36,814 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-13) [11b9de33] Correlation ID: 6f4c4a78, Job ID: c507cab9-0b00-4b57-ab60-3ceb4a686b87, Call Stack: null, Custom Event ID: -1, Message: Add-Disk operation of testcentos7gui_Disk1 was initiated on VM testcentos7gui by sblanchet.
>2014-09-11 14:38:36,816 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp--127.0.0.1-8702-13) [11b9de33] BaseAsyncTask::startPollingTask: Starting to poll task 64e8ea11-0520-4efa-ad69-ed270647c2d2.
>2014-09-11 14:38:37,166 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-94) [1cdec8ff] START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 7c067e6b
>2014-09-11 14:38:37,195 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-94) [1cdec8ff] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 7c067e6b
>2014-09-11 14:38:37,444 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-94) [1cdec8ff] START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 3d5667b6
>2014-09-11 14:38:37,469 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-94) [1cdec8ff] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 3d5667b6
>2014-09-11 14:38:37,475 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-94) [1cdec8ff] START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 311132d6
>2014-09-11 14:38:37,495 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-94) [1cdec8ff] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 311132d6
>2014-09-11 14:38:42,503 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-6) START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 7d37094a
>2014-09-11 14:38:42,547 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-6) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 7d37094a
>2014-09-11 14:38:42,561 INFO  [org.ovirt.engine.core.bll.scheduling.PolicyUnitImpl] (DefaultQuartzScheduler_Worker-15) [3008836c] There is no over-utilized host in cluster Cluster1
>2014-09-11 14:38:42,765 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-6) START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 22f3d33e
>2014-09-11 14:38:42,788 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-6) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 22f3d33e
>2014-09-11 14:38:42,794 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-6) START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 48c3370f
>2014-09-11 14:38:42,813 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-6) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 48c3370f
>2014-09-11 14:38:44,266 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler_Worker-18) [163eee19] START, GlusterTasksListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 44bb59e9
>2014-09-11 14:38:44,320 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler_Worker-18) [163eee19] FINISH, GlusterTasksListVDSCommand, return: [], log id: 44bb59e9
>2014-09-11 14:38:44,450 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler_Worker-18) [163eee19] START, GlusterTasksListVDSCommand(HostName = ouragan, HostId = d8e5bf92-b56c-4dee-81f0-bc61b86e6541), log id: 24bbded4
>2014-09-11 14:38:44,504 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler_Worker-18) [163eee19] FINISH, GlusterTasksListVDSCommand, return: [], log id: 24bbded4
>2014-09-11 14:38:44,508 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler_Worker-18) [163eee19] START, GlusterTasksListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 7fc01a7
>2014-09-11 14:38:44,535 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler_Worker-18) [163eee19] FINISH, GlusterTasksListVDSCommand, return: [], log id: 7fc01a7
>2014-09-11 14:38:46,656 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-21) [5a79899e] Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
>2014-09-11 14:38:46,664 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-21) [5a79899e] SPMAsyncTask::PollTask: Polling task 64e8ea11-0520-4efa-ad69-ed270647c2d2 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2014-09-11 14:38:46,677 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-21) [5a79899e] BaseAsyncTask::OnTaskEndSuccess: Task 64e8ea11-0520-4efa-ad69-ed270647c2d2 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2014-09-11 14:38:46,678 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-21) [5a79899e] CommandAsyncTask::EndActionIfNecessary: All tasks of command 8c0e1dd7-0195-4d4c-8b64-2f1c0419e3da has ended -> executing endAction
>2014-09-11 14:38:46,679 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-21) [5a79899e] CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: 8c0e1dd7-0195-4d4c-8b64-2f1c0419e3da): calling endAction .
>2014-09-11 14:38:46,681 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-48) [5a79899e] CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction AddDisk, executionIndex: 0
>2014-09-11 14:38:46,690 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (org.ovirt.thread.pool-6-thread-48) [6f4c4a78] Ending command successfully: org.ovirt.engine.core.bll.AddDiskCommand
>2014-09-11 14:38:46,701 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (org.ovirt.thread.pool-6-thread-48) [11b9de33] Ending command successfully: org.ovirt.engine.core.bll.AddImageFromScratchCommand
>2014-09-11 14:38:46,713 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-6-thread-48) [11b9de33] START, GetImageInfoVDSCommand( storagePoolId = 48ca3019-9dbf-4ef3-98e9-08105d396350, ignoreFailoverLimit = false, storageDomainId = 5ef8572c-0ab5-4491-994a-e4c30230a525, imageGroupId = b24d1090-4c9b-4f74-9be9-a9f1950c201b, imageId = 119bed1b-3b2e-4790-ae75-7eed3d5e386c), log id: acc9d4d
>2014-09-11 14:38:46,743 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-6-thread-48) [11b9de33] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@a96114c6, log id: acc9d4d
>2014-09-11 14:38:46,788 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-48) Correlation ID: 6f4c4a78, Job ID: c507cab9-0b00-4b57-ab60-3ceb4a686b87, Call Stack: null, Custom Event ID: -1, Message: The disk testcentos7gui_Disk1 was successfully added to VM testcentos7gui.
>2014-09-11 14:38:46,790 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-48) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk completed, handling the result.
>2014-09-11 14:38:46,791 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-48) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk succeeded, clearing tasks.
>2014-09-11 14:38:46,797 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-6-thread-48) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 64e8ea11-0520-4efa-ad69-ed270647c2d2
>2014-09-11 14:38:46,798 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-48) START, SPMClearTaskVDSCommand( storagePoolId = 48ca3019-9dbf-4ef3-98e9-08105d396350, ignoreFailoverLimit = false, taskId = 64e8ea11-0520-4efa-ad69-ed270647c2d2), log id: 13590cb7
>2014-09-11 14:38:46,829 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-48) START, HSMClearTaskVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198, taskId=64e8ea11-0520-4efa-ad69-ed270647c2d2), log id: 3b51cc4d
>2014-09-11 14:38:46,839 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-48) FINISH, HSMClearTaskVDSCommand, log id: 3b51cc4d
>2014-09-11 14:38:46,839 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-48) FINISH, SPMClearTaskVDSCommand, log id: 13590cb7
>2014-09-11 14:38:46,845 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-6-thread-48) BaseAsyncTask::RemoveTaskFromDB: Removed task 64e8ea11-0520-4efa-ad69-ed270647c2d2 from DataBase
>2014-09-11 14:38:46,846 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-48) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 8c0e1dd7-0195-4d4c-8b64-2f1c0419e3da
>2014-09-11 14:38:47,827 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-100) [41f921dc] START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 192bb642
>2014-09-11 14:38:47,915 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-100) [41f921dc] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 192bb642
>2014-09-11 14:38:48,129 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-100) [41f921dc] START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 2e9d8df5
>2014-09-11 14:38:48,149 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-100) [41f921dc] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 2e9d8df5
>2014-09-11 14:38:48,155 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-100) [41f921dc] START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 3ce23ac5
>2014-09-11 14:38:48,181 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-100) [41f921dc] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 3ce23ac5
>2014-09-11 14:38:53,190 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-5) [36ae313c] START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 78d29963
>2014-09-11 14:38:53,228 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-5) [36ae313c] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 78d29963
>2014-09-11 14:38:53,445 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-5) [36ae313c] START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 7ed257a6
>2014-09-11 14:38:53,467 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-5) [36ae313c] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 7ed257a6
>2014-09-11 14:38:53,473 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-5) [36ae313c] START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 21e20292
>2014-09-11 14:38:53,512 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-5) [36ae313c] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 21e20292
>2014-09-11 14:38:58,524 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-52) [386ddf2f] START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 6e475a29
>2014-09-11 14:38:58,583 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-52) [386ddf2f] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 6e475a29
>2014-09-11 14:38:58,807 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-52) [386ddf2f] START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 69e476e8
>2014-09-11 14:38:58,824 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-52) [386ddf2f] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 69e476e8
>2014-09-11 14:38:58,830 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-52) [386ddf2f] START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 139388af
>2014-09-11 14:38:58,852 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-52) [386ddf2f] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 139388af
>2014-09-11 14:39:03,861 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-98) [7b7a44e4] START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 2c7f970b
>2014-09-11 14:39:03,939 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-98) [7b7a44e4] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 2c7f970b
>2014-09-11 14:39:04,157 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-98) [7b7a44e4] START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 1e26e4ee
>2014-09-11 14:39:04,176 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-98) [7b7a44e4] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 1e26e4ee
>2014-09-11 14:39:04,182 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-98) [7b7a44e4] START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 331aa304
>2014-09-11 14:39:04,249 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-98) [7b7a44e4] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 331aa304
>2014-09-11 14:39:04,973 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-12) [5953ebf8] Lock Acquired to object EngineLock [exclusiveLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM
>, sharedLocks= ]
>2014-09-11 14:39:05,004 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-12) [5953ebf8] START, IsVmDuringInitiatingVDSCommand( vmId = 5974b4fc-d37d-49a1-a102-6ab68775ce07), log id: 327d54e3
>2014-09-11 14:39:05,005 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-12) [5953ebf8] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 327d54e3
>2014-09-11 14:39:05,083 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] Running command: RunVmCommand internal: false. Entities affected :  ID: 5974b4fc-d37d-49a1-a102-6ab68775ce07 Type: VM
>2014-09-11 14:39:05,173 INFO  [org.ovirt.engine.core.bll.scheduling.policyunits.HaReservationWeightPolicyUnit] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] Started HA reservation scoring method
>2014-09-11 14:39:05,321 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] Running VM with attached cd CentOS-7.0-1406-x86_64-DVD.iso
>2014-09-11 14:39:05,324 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] START, IsoPrefixVDSCommand(HostName = eurus, HostId = a9a66087-f7c1-4953-b55e-17feb784438e, storagePoolId=48ca3019-9dbf-4ef3-98e9-08105d396350), log id: 3bf457b5
>2014-09-11 14:39:05,326 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/hupe.abes.fr:_ovirt__iso/cdc09bbb-fd7a-4a48-8b6a-cd7bccbecc3d/images/11111111-1111-1111-1111-111111111111, log id: 3bf457b5
>2014-09-11 14:39:05,345 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] START, CreateVmVDSCommand(HostName = eurus, HostId = a9a66087-f7c1-4953-b55e-17feb784438e, vmId=5974b4fc-d37d-49a1-a102-6ab68775ce07, vm=VM [testcentos7gui]), log id: 23ad538c
>2014-09-11 14:39:05,357 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] START, CreateVDSCommand(HostName = eurus, HostId = a9a66087-f7c1-4953-b55e-17feb784438e, vmId=5974b4fc-d37d-49a1-a102-6ab68775ce07, vm=VM [testcentos7gui]), log id: 5b88a924
>2014-09-11 14:39:05,458 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=1024,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=rhel6.5.0,keyboardLayout=en-us,memGuaranteedSize=682,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,maxVCpus=160,timeOffset=0,transparentHugePages=true,vmId=5974b4fc-d37d-49a1-a102-6ab68775ce07,devices=[{specParams={vram=32768, heads=1}, device=qxl, type=video, deviceId=c356c443-83e1-4a95-b3c4-743035fa075a}, {shared=false, iface=ide, index=2, specParams={path=CentOS-7.0-1406-x86_64-DVD.iso}, path=/rhev/data-center/mnt/hupe.abes.fr:_ovirt__iso/cdc09bbb-fd7a-4a48-8b6a-cd7bccbecc3d/images/11111111-1111-1111-1111-111111111111/CentOS-7.0-1406-x86_64-DVD.iso, device=cdrom, type=disk, readonly=true, deviceId=61dd30c1-aba1-4769-8fee-9e42d1a6a9c9}, {shared=false, iface=ide, index=2, specParams={path=CentOS-7.0-1406-x86_64-DVD.iso}, path=/rhev/data-center/mnt/hupe.abes.fr:_ovirt__iso/cdc09bbb-fd7a-4a48-8b6a-cd7bccbecc3d/images/11111111-1111-1111-1111-111111111111/CentOS-7.0-1406-x86_64-DVD.iso, device=cdrom, type=disk, readonly=true, deviceId=65b847e4-74ec-409c-b927-85f2447f3650}, {shared=false, volumeID=119bed1b-3b2e-4790-ae75-7eed3d5e386c, index=0, iface=virtio, imageID=b24d1090-4c9b-4f74-9be9-a9f1950c201b, domainID=5ef8572c-0ab5-4491-994a-e4c30230a525, specParams={}, optional=false, propagateErrors=off, device=disk, poolID=48ca3019-9dbf-4ef3-98e9-08105d396350, format=raw, type=disk, readonly=false, deviceId=b24d1090-4c9b-4f74-9be9-a9f1950c201b}, {nicModel=pv, specParams={outbound={}, inbound={}}, macAddr=00:1a:4a:22:1e:59, device=bridge, linkActive=true, type=interface, filter=vdsm-no-mac-spoofing, network=brv11, deviceId=0d12b244-baff-4bad-8f7d-7e8b184e20a5}, {specParams={model=virtio}, device=memballoon, type=balloon, deviceId=5ef09f26-7058-4b30-93ce-1468479524b0}, {index=0, model=virtio-scsi, specParams={}, device=scsi, type=controller, deviceId=5a93e191-f4b7-475a-98a3-e42d046dfbe1}],acpiEnable=true,vmName=testcentos7gui,cpuType=Westmere,custom={}
>2014-09-11 14:39:05,467 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] FINISH, CreateVDSCommand, log id: 5b88a924
>2014-09-11 14:39:05,474 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 23ad538c
>2014-09-11 14:39:05,475 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] Lock freed to object EngineLock [exclusiveLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM
>, sharedLocks= ]
>2014-09-11 14:39:05,481 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-37) [5953ebf8] Correlation ID: 5953ebf8, Job ID: 8805ac08-bb88-4240-912f-dde1c7a38709, Call Stack: null, Custom Event ID: -1, Message: VM testcentos7gui was started by sblanchet (Host: eurus).
>2014-09-11 14:39:09,260 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-19) START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: b45c4da
>2014-09-11 14:39:09,326 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-19) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: b45c4da
>2014-09-11 14:39:09,550 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-19) START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 23428acf
>2014-09-11 14:39:09,568 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-19) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 23428acf
>2014-09-11 14:39:09,574 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-19) START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 36a6359c
>2014-09-11 14:39:09,603 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-19) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 36a6359c
>2014-09-11 14:39:10,462 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-43) START, DestroyVDSCommand(HostName = eurus, HostId = a9a66087-f7c1-4953-b55e-17feb784438e, vmId=5974b4fc-d37d-49a1-a102-6ab68775ce07, force=false, secondsToWait=0, gracefully=false), log id: 18e9eb45
>2014-09-11 14:39:11,664 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-43) FINISH, DestroyVDSCommand, log id: 18e9eb45
>2014-09-11 14:39:11,731 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-43) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VM testcentos7gui is down. Exit message: internal error process exited while connecting to monitor: qemu-kvm: -drive file=/rhev/data-center/mnt/hupe.abes.fr:_ovirt__iso/cdc09bbb-fd7a-4a48-8b6a-cd7bccbecc3d/images/11111111-1111-1111-1111-111111111111/CentOS-7.0-1406-x86_64-DVD.iso,if=none,media=cdrom,id=drive-ide0-1-0,readonly=on,format=raw,serial=: Duplicate ID 'drive-ide0-1-0' for drive
>.
>2014-09-11 14:39:11,734 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-43) Running on vds during rerun failed vm: null
>2014-09-11 14:39:11,736 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-43) VM testcentos7gui (5974b4fc-d37d-49a1-a102-6ab68775ce07) is running in db and not running in VDS eurus
>2014-09-11 14:39:11,737 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-43) START, FullListVdsCommand(HostName = eurus, HostId = a9a66087-f7c1-4953-b55e-17feb784438e, vds=Host[eurus,a9a66087-f7c1-4953-b55e-17feb784438e], vmIds=[5974b4fc-d37d-49a1-a102-6ab68775ce07]), log id: 1fe9f9f1
>2014-09-11 14:39:11,744 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-43) FINISH, FullListVdsCommand, return: [], log id: 1fe9f9f1
>2014-09-11 14:39:11,749 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-43) Rerun vm 5974b4fc-d37d-49a1-a102-6ab68775ce07. Called from vds eurus
>2014-09-11 14:39:11,756 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-45) Correlation ID: 5953ebf8, Job ID: 8805ac08-bb88-4240-912f-dde1c7a38709, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM testcentos7gui on Host eurus.
>2014-09-11 14:39:11,775 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-45) Lock Acquired to object EngineLock [exclusiveLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM
>, sharedLocks= ]
>2014-09-11 14:39:11,805 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-6-thread-45) START, IsVmDuringInitiatingVDSCommand( vmId = 5974b4fc-d37d-49a1-a102-6ab68775ce07), log id: 28a0c083
>2014-09-11 14:39:11,806 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-6-thread-45) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 28a0c083
>2014-09-11 14:39:11,856 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-45) Running command: RunVmCommand internal: false. Entities affected :  ID: 5974b4fc-d37d-49a1-a102-6ab68775ce07 Type: VM
>2014-09-11 14:39:11,904 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-45) Running VM with attached cd CentOS-7.0-1406-x86_64-DVD.iso
>2014-09-11 14:39:11,907 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (org.ovirt.thread.pool-6-thread-45) START, IsoPrefixVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556, storagePoolId=48ca3019-9dbf-4ef3-98e9-08105d396350), log id: 4b6cee3
>2014-09-11 14:39:11,908 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (org.ovirt.thread.pool-6-thread-45) FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/hupe.abes.fr:_ovirt__iso/cdc09bbb-fd7a-4a48-8b6a-cd7bccbecc3d/images/11111111-1111-1111-1111-111111111111, log id: 4b6cee3
>2014-09-11 14:39:11,920 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-6-thread-45) START, CreateVmVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556, vmId=5974b4fc-d37d-49a1-a102-6ab68775ce07, vm=VM [testcentos7gui]), log id: 18e6eaf9
>2014-09-11 14:39:11,924 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-45) START, CreateVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556, vmId=5974b4fc-d37d-49a1-a102-6ab68775ce07, vm=VM [testcentos7gui]), log id: 76eb280b
>2014-09-11 14:39:12,056 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-45) org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=1024,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=rhel6.5.0,keyboardLayout=en-us,memGuaranteedSize=682,nice=0,display=qxl,smartcardEnable=fa
>2014-09-11 14:39:14,690 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-49) [6c4e2815] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 5b3f476d
>2014-09-11 14:39:14,937 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-49) [6c4e2815] START, GlusterVolumesListVDSCommand(HostName = chergui, HostId = b02497ce-ec79-4fc6-8306-a603beca8198), log id: 1a45d26f
>2014-09-11 14:39:14,953 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-49) [6c4e2815] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 1a45d26f
>2014-09-11 14:39:14,962 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-49) [6c4e2815] START, GlusterVolumesListVDSCommand(HostName = chinook, HostId = 10e46f83-324c-41a3-96a1-486881500bb4), log id: 4522cf81
>2014-09-11 14:39:14,993 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-49) [6c4e2815] FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 4522cf81
>2014-09-11 14:39:18,745 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-33) [1521c58d] START, DestroyVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556, vmId=5974b4fc-d37d-49a1-a102-6ab68775ce07, force=false, secondsToWait=0, gracefully=false), log id: b590e64
>2014-09-11 14:39:19,990 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-33) [1521c58d] FINISH, DestroyVDSCommand, log id: b590e64
>2014-09-11 14:39:20,002 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-28) START, GlusterVolumesListVDSCommand(HostName = haboob, HostId = 88f15bc8-4dae-4990-b7e4-e82d48709556), log id: 371f342e
>2014-09-11 14:39:20,007 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-33) [1521c58d] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VM testcentos7gui is down. Exit message: internal error process exited while connecting to monitor: qemu-kvm: -drive file=/rhev/data-center/mnt/hupe.abes.fr:_ovirt__iso/cdc09bbb-fd7a-4a48-8b6a-cd7bccbecc3d/images/11111111-1111-1111-1111-111111111111/CentOS-7.0-1406-x86_64-DVD.iso,if=none,media=cdrom,id=drive-ide0-1-0,readonly=on,format=raw,serial=: Duplicate ID 'drive-ide0-1-0' for drive
>.
>2014-09-11 14:39:20,009 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-33) [1521c58d] Running on vds during rerun failed vm: null
>2014-09-11 14:39:20,105 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-28) FINISH, GlusterVolumesListVDSCommand, return: {}, log id: 371f342e
>2014-09-11 14:39:20,171 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-33) [1521c58d] VM testcentos7gui (5974b4fc-d37d-49a1-a102-6ab68775ce07) is running in db and not running in VDS haboob
>2014-09-11 14:39:20,269 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-33) [1521c58d] Rerun vm 5974b4fc-d37d-49a1-a102-6ab68775ce07. Called from vds haboob
>2014-09-11 14:39:20,271 WARN  [org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil] (org.ovirt.thread.pool-6-thread-37) Executing a command: java.util.concurrent.FutureTask , but note that there are 1 tasks in the queue.
>2014-09-11 14:39:20,278 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-37) [1521c58d] Correlation ID: 5953ebf8, Job ID: 8805ac08-bb88-4240-912f-dde1c7a38709, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM testcentos7gui on Host haboob.
>2014-09-11 14:39:20,296 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-37) [1521c58d] Lock Acquired to object EngineLock [exclusiveLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM
>, sharedLocks= ]
>2014-09-11 14:39:20,332 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-6-thread-37) [1521c58d] START, IsVmDuringInitiatingVDSCommand( vmId = 5974b4fc-d37d-49a1-a102-6ab68775ce07), log id: 31fe2ca0
>2014-09-11 14:39:20,333 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-6-thread-37) [1521c58d] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 31fe2ca0
>2014-09-11 14:39:20,342 WARN  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-37) [1521c58d] CanDoAction of action RunVm failed. Reasons:VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,SCHEDULING_ALL_HOSTS_FILTERED_OUT
>2014-09-11 14:39:20,343 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-37) [1521c58d] Lock freed to object EngineLock [exclusiveLocks= key: 5974b4fc-d37d-49a1-a102-6ab68775ce07 value: VM
>, sharedLocks= ]
>2014-09-11 14:39:20,351 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-37) [1521c58d] Correlation ID: 5953ebf8, Job ID: 8805ac08-bb88-4240-912f-dde1c7a38709, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM testcentos7gui (User: sblanchet).

Actions: View

Attachments on bug 1109880: 909152 | 909154 | 936543 | 936544