Attachment 827209 Details for Bug 1033064 – ovirt_engine_3_3_1-2_log failed to start vm on local dc

ovirt_engine_3_3_1-2_log failed to start vm on local dc

test4_engine_log (text/plain), 39.01 KB, created by Sven Kieske on 2013-11-21 13:57:56 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Sven Kieske

Created: 2013-11-21 13:57:56 UTC

Size: 39.01 KB

patch

obsolete

>2013-11-21 15:09:30,385 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp--127.0.0.1-8702-7) Lock Acquired to object EngineLock [exclusiveLocks= key: test4vm value: VM_NAME
>, sharedLocks= ]
>2013-11-21 15:09:30,417 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp--127.0.0.1-8702-7) Running command: AddVmFromScratchCommand internal: false. Entities affected :  ID: 94815635-6bab-4157-8e6b-ddb0945da3d0 Type: VdsGroups
>2013-11-21 15:09:30,484 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) Correlation ID: 9967f6f, Job ID: 9f3f8893-b1e6-4445-aa0a-beca0abea848, Call Stack: null, Custom Event ID: -1, Message: VM test4vm was created by admin@internal.
>2013-11-21 15:09:30,492 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp--127.0.0.1-8702-7) Lock freed to object EngineLock [exclusiveLocks= key: test4vm value: VM_NAME
>, sharedLocks= ]
>2013-11-21 15:09:30,527 INFO  [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp--127.0.0.1-8702-5) MultipleActionsRunner of type AddVmInterface invoked with no actions
>2013-11-21 15:09:30,546 INFO  [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp--127.0.0.1-8702-4) MultipleActionsRunner of type UpdateVmInterface invoked with no actions
>2013-11-21 15:09:30,561 INFO  [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp--127.0.0.1-8702-8) MultipleActionsRunner of type RemoveVmInterface invoked with no actions
>2013-11-21 15:09:37,102 INFO  [org.ovirt.engine.core.bll.network.vm.AddVmInterfaceCommand] (ajp--127.0.0.1-8702-1) Running command: AddVmInterfaceCommand internal: false. Entities affected :  ID: faff3bf2-b15c-476b-bd92-c8c746282704 Type: VM,  ID: d3480dfa-633b-4318-b45a-899212dd6259 Type: VnicProfile
>2013-11-21 15:09:37,150 INFO  [org.ovirt.engine.core.bll.network.vm.ActivateDeactivateVmNicCommand] (ajp--127.0.0.1-8702-1) Running command: ActivateDeactivateVmNicCommand internal: true. Entities affected :  ID: faff3bf2-b15c-476b-bd92-c8c746282704 Type: VM
>2013-11-21 15:09:37,167 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-1) Correlation ID: 11771b61, Call Stack: null, Custom Event ID: -1, Message: Network Interface nic1 (VirtIO) was plugged to VM test4vm. (User: admin@internal)
>2013-11-21 15:09:37,183 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-1) Correlation ID: 5cac08de, Call Stack: null, Custom Event ID: -1, Message: Interface nic1 (VirtIO) was added to VM test4vm. (User: admin@internal)
>2013-11-21 15:09:45,386 INFO  [org.ovirt.engine.core.bll.SetVmTicketCommand] (ajp--127.0.0.1-8702-5) Running command: SetVmTicketCommand internal: false. Entities affected :  ID: 83e2a469-a162-4e0b-95a6-ed20a924529c Type: VM
>2013-11-21 15:09:45,505 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (ajp--127.0.0.1-8702-5) FINISH, SetVmTicketVDSCommand, log id: 4744124e
>2013-11-21 15:09:45,541 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-5) Correlation ID: 64dc4fa5, Call Stack: null, Custom Event ID: -1, Message: user admin@internal initiated console session for VM fedora19
>2013-11-21 15:09:45,543 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (ajp--127.0.0.1-8702-3) Lock Acquired to object EngineLock [exclusiveLocks= key: faff3bf2-b15c-476b-bd92-c8c746282704 value: VM_DISK_BOOT
>, sharedLocks= key: faff3bf2-b15c-476b-bd92-c8c746282704 value: VM
>]
>2013-11-21 15:09:45,566 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (ajp--127.0.0.1-8702-3) Running command: AddDiskCommand internal: false. Entities affected :  ID: faff3bf2-b15c-476b-bd92-c8c746282704 Type: VM,  ID: 2d1a52ae-877b-482e-9af2-1b5ae6c3d182 Type: Storage
>2013-11-21 15:09:45,668 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (ajp--127.0.0.1-8702-3) Running command: AddImageFromScratchCommand internal: true. Entities affected :  ID: 2d1a52ae-877b-482e-9af2-1b5ae6c3d182 Type: Storage
>2013-11-21 15:09:45,699 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (ajp--127.0.0.1-8702-3) Lock freed to object EngineLock [exclusiveLocks= key: faff3bf2-b15c-476b-bd92-c8c746282704 value: VM_DISK_BOOT
>, sharedLocks= key: faff3bf2-b15c-476b-bd92-c8c746282704 value: VM
>]
>2013-11-21 15:09:45,700 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp--127.0.0.1-8702-3) START, CreateImageVDSCommand( storagePoolId = 2240b7ff-3dad-4568-90d1-853d196c6e11, ignoreFailoverLimit = false, storageDomainId = 2d1a52ae-877b-482e-9af2-1b5ae6c3d182, imageGroupId = bb0f6ec3-2ebf-43e8-a28e-8db7a171f161, imageSizeInBytes = 10737418240, volumeFormat = RAW, newImageId = 382b3629-f307-4d33-905a-7995f89d8010, newImageDescription = ), log id: 344353be
>2013-11-21 15:09:45,700 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp--127.0.0.1-8702-3) -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID
>2013-11-21 15:09:45,701 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp--127.0.0.1-8702-3) -- createVolume parameters:
>                sdUUID=2d1a52ae-877b-482e-9af2-1b5ae6c3d182
>                spUUID=2240b7ff-3dad-4568-90d1-853d196c6e11
>                imgGUID=bb0f6ec3-2ebf-43e8-a28e-8db7a171f161
>                size=10,737,418,240 bytes
>                volFormat=RAW
>                volType=Sparse
>                volUUID=382b3629-f307-4d33-905a-7995f89d8010
>                descr=
>                srcImgGUID=00000000-0000-0000-0000-000000000000
>                srcVolUUID=00000000-0000-0000-0000-000000000000
>                
>2013-11-21 15:09:45,774 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp--127.0.0.1-8702-3) FINISH, CreateImageVDSCommand, return: 382b3629-f307-4d33-905a-7995f89d8010, log id: 344353be
>2013-11-21 15:09:45,827 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp--127.0.0.1-8702-3) CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 209cc7eb-405b-4f8b-b037-d261dccd6ec3
>2013-11-21 15:09:45,828 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp--127.0.0.1-8702-3) CommandMultiAsyncTasks::AttachTask: Attaching task 54a1082b-b9b9-4aee-99f6-12a87c981951 to command 209cc7eb-405b-4f8b-b037-d261dccd6ec3.
>2013-11-21 15:09:45,841 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp--127.0.0.1-8702-3) Adding task 54a1082b-b9b9-4aee-99f6-12a87c981951 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2013-11-21 15:09:45,883 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) Correlation ID: 4cf39357, Job ID: 531c0a1c-07d0-4b1c-92a6-d4d4a91b5396, Call Stack: null, Custom Event ID: -1, Message: Add-Disk operation of test4vm_Disk1 was initiated on VM test4vm by admin@internal.
>2013-11-21 15:09:45,884 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp--127.0.0.1-8702-3) BaseAsyncTask::StartPollingTask: Starting to poll task 54a1082b-b9b9-4aee-99f6-12a87c981951.
>2013-11-21 15:09:53,592 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-19) Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
>2013-11-21 15:09:53,600 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-19) SPMAsyncTask::PollTask: Polling task 54a1082b-b9b9-4aee-99f6-12a87c981951 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2013-11-21 15:09:53,609 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-19) BaseAsyncTask::OnTaskEndSuccess: Task 54a1082b-b9b9-4aee-99f6-12a87c981951 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2013-11-21 15:09:53,610 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-19) CommandAsyncTask::EndActionIfNecessary: All tasks of command 209cc7eb-405b-4f8b-b037-d261dccd6ec3 has ended -> executing EndAction
>2013-11-21 15:09:53,610 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (DefaultQuartzScheduler_Worker-19) CommandAsyncTask::EndAction: Ending action for 1 tasks (command ID: 209cc7eb-405b-4f8b-b037-d261dccd6ec3): calling EndAction .
>2013-11-21 15:09:53,611 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (pool-6-thread-49) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to EndAction AddDisk, executionIndex: 0
>2013-11-21 15:09:53,625 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (pool-6-thread-49) [4cf39357] Ending command successfully: org.ovirt.engine.core.bll.AddDiskCommand
>2013-11-21 15:09:53,629 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (pool-6-thread-49) [1a955bf0] Ending command successfully: org.ovirt.engine.core.bll.AddImageFromScratchCommand
>2013-11-21 15:09:53,636 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-6-thread-49) [1a955bf0] START, GetImageInfoVDSCommand( storagePoolId = 2240b7ff-3dad-4568-90d1-853d196c6e11, ignoreFailoverLimit = false, storageDomainId = 2d1a52ae-877b-482e-9af2-1b5ae6c3d182, imageGroupId = bb0f6ec3-2ebf-43e8-a28e-8db7a171f161, imageId = 382b3629-f307-4d33-905a-7995f89d8010), log id: 143142a8
>2013-11-21 15:09:57,481 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-6-thread-49) [1a955bf0] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@d4a41cce, log id: 143142a8
>2013-11-21 15:09:57,517 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-6-thread-49) Correlation ID: 4cf39357, Job ID: 531c0a1c-07d0-4b1c-92a6-d4d4a91b5396, Call Stack: null, Custom Event ID: -1, Message: The disk test4vm_Disk1 was successfully added to VM test4vm.
>2013-11-21 15:09:57,517 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (pool-6-thread-49) CommandAsyncTask::HandleEndActionResult [within thread]: EndAction for action type AddDisk completed, handling the result.
>2013-11-21 15:09:57,518 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (pool-6-thread-49) CommandAsyncTask::HandleEndActionResult [within thread]: EndAction for action type AddDisk succeeded, clearing tasks.
>2013-11-21 15:09:57,525 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-6-thread-49) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 54a1082b-b9b9-4aee-99f6-12a87c981951
>2013-11-21 15:09:57,526 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-6-thread-49) START, SPMClearTaskVDSCommand( storagePoolId = 2240b7ff-3dad-4568-90d1-853d196c6e11, ignoreFailoverLimit = false, taskId = 54a1082b-b9b9-4aee-99f6-12a87c981951), log id: 5e1b38a
>2013-11-21 15:09:57,527 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-6-thread-49) START, HSMClearTaskVDSCommand(HostName = test4, HostId = 0b4c0615-8f00-43c5-b306-4db8f1dfb1fd, taskId=54a1082b-b9b9-4aee-99f6-12a87c981951), log id: 24d22c0a
>2013-11-21 15:09:57,534 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-6-thread-49) FINISH, HSMClearTaskVDSCommand, log id: 24d22c0a
>2013-11-21 15:09:57,535 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-6-thread-49) FINISH, SPMClearTaskVDSCommand, log id: 5e1b38a
>2013-11-21 15:09:57,542 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-6-thread-49) BaseAsyncTask::RemoveTaskFromDB: Removed task 54a1082b-b9b9-4aee-99f6-12a87c981951 from DataBase
>2013-11-21 15:09:57,542 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (pool-6-thread-49) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 209cc7eb-405b-4f8b-b037-d261dccd6ec3
>2013-11-21 15:11:23,593 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-6) Setting new tasks map. The map contains now 0 tasks
>2013-11-21 15:11:23,593 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-6) Cleared all tasks of pool 2240b7ff-3dad-4568-90d1-853d196c6e11.
>2013-11-21 15:11:49,274 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-10) [1929ae43] Lock Acquired to object EngineLock [exclusiveLocks= key: faff3bf2-b15c-476b-bd92-c8c746282704 value: VM
>, sharedLocks= ]
>2013-11-21 15:11:49,280 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-10) [1929ae43] START, IsVmDuringInitiatingVDSCommand( vmId = faff3bf2-b15c-476b-bd92-c8c746282704), log id: 43c5e455
>2013-11-21 15:11:49,281 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-10) [1929ae43] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 43c5e455
>2013-11-21 15:11:49,295 WARN  [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-10) [1929ae43] CanDoAction of action RunVm failed. Reasons:VAR__ACTION__RUN,VAR__TYPE__VM,VM_CANNOT_RUN_FROM_CD_WITHOUT_ACTIVE_STORAGE_DOMAIN_ISO
>2013-11-21 15:11:49,296 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-10) [1929ae43] Lock freed to object EngineLock [exclusiveLocks= key: faff3bf2-b15c-476b-bd92-c8c746282704 value: VM
>, sharedLocks= ]
>2013-11-21 15:12:05,308 INFO  [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] (pool-6-thread-50) [74bfad75] Running command: AttachStorageDomainToPoolCommand internal: false. Entities affected :  ID: 27945043-e2a8-4c1f-bd6b-bf39ba2a6d97 Type: Storage
>2013-11-21 15:12:05,318 INFO  [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (pool-6-thread-49) Running command: ConnectStorageToVdsCommand internal: true. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2013-11-21 15:12:05,320 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-6-thread-49) START, ConnectStorageServerVDSCommand(HostName = test4, HostId = 0b4c0615-8f00-43c5-b306-4db8f1dfb1fd, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: efe3a643-8004-44c4-ae3a-a575d6faeb25, connection: management:/var/lib/exports/iso, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 12fbcb29
>2013-11-21 15:12:06,415 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-6-thread-49) FINISH, ConnectStorageServerVDSCommand, return: {efe3a643-8004-44c4-ae3a-a575d6faeb25=0}, log id: 12fbcb29
>2013-11-21 15:12:06,416 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (pool-6-thread-50) [74bfad75] START, AttachStorageDomainVDSCommand( storagePoolId = 2240b7ff-3dad-4568-90d1-853d196c6e11, ignoreFailoverLimit = false, storageDomainId = 27945043-e2a8-4c1f-bd6b-bf39ba2a6d97), log id: 6ebd13be
>2013-11-21 15:12:14,295 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (pool-6-thread-50) [74bfad75] FINISH, AttachStorageDomainVDSCommand, log id: 6ebd13be
>2013-11-21 15:12:14,316 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-6-thread-50) Lock Acquired to object EngineLock [exclusiveLocks= key: 27945043-e2a8-4c1f-bd6b-bf39ba2a6d97 value: STORAGE
>, sharedLocks= ]
>2013-11-21 15:12:14,350 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-6-thread-50) Running command: ActivateStorageDomainCommand internal: true. Entities affected :  ID: 27945043-e2a8-4c1f-bd6b-bf39ba2a6d97 Type: Storage
>2013-11-21 15:12:14,366 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-6-thread-50) Lock freed to object EngineLock [exclusiveLocks= key: 27945043-e2a8-4c1f-bd6b-bf39ba2a6d97 value: STORAGE
>, sharedLocks= ]
>2013-11-21 15:12:14,366 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-6-thread-50) ActivateStorage Domain. Before Connect all hosts to pool. Time:11/21/13 3:12 PM
>2013-11-21 15:12:14,368 INFO  [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (pool-6-thread-49) Running command: ConnectStorageToVdsCommand internal: true. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2013-11-21 15:12:14,370 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-6-thread-49) START, ConnectStorageServerVDSCommand(HostName = test4, HostId = 0b4c0615-8f00-43c5-b306-4db8f1dfb1fd, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: efe3a643-8004-44c4-ae3a-a575d6faeb25, connection: management:/var/lib/exports/iso, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: fd50d4b
>2013-11-21 15:12:14,912 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-6-thread-49) FINISH, ConnectStorageServerVDSCommand, return: {efe3a643-8004-44c4-ae3a-a575d6faeb25=0}, log id: fd50d4b
>2013-11-21 15:12:14,913 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (pool-6-thread-50) START, ActivateStorageDomainVDSCommand( storagePoolId = 2240b7ff-3dad-4568-90d1-853d196c6e11, ignoreFailoverLimit = false, storageDomainId = 27945043-e2a8-4c1f-bd6b-bf39ba2a6d97), log id: 722876c
>2013-11-21 15:12:18,430 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (pool-6-thread-50) FINISH, ActivateStorageDomainVDSCommand, log id: 722876c
>2013-11-21 15:12:18,430 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-6-thread-50) ActivateStorage Domain. After Connect all hosts to pool. Time:11/21/13 3:12 PM
>2013-11-21 15:12:18,434 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.RefreshStoragePoolVDSCommand] (pool-6-thread-49) START, RefreshStoragePoolVDSCommand(HostName = test4, HostId = 0b4c0615-8f00-43c5-b306-4db8f1dfb1fd, storagePoolId = 2240b7ff-3dad-4568-90d1-853d196c6e11, masterStorageDomainId=2d1a52ae-877b-482e-9af2-1b5ae6c3d182, masterVersion=1), log id: 2ea3226
>2013-11-21 15:12:18,459 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.RefreshStoragePoolVDSCommand] (pool-6-thread-49) FINISH, RefreshStoragePoolVDSCommand, log id: 2ea3226
>2013-11-21 15:12:18,460 INFO  [org.ovirt.engine.core.bll.storage.RefreshPoolSingleAsyncOperation] (pool-6-thread-49) Refreshed vds test4 in pool test4
>2013-11-21 15:12:18,479 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-6-thread-50) ActivateStorage Domain. After change storage pool status in vds. Time:11/21/13 3:12 PM
>2013-11-21 15:12:18,481 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetIsoListVDSCommand] (pool-6-thread-49) START, GetIsoListVDSCommand( storagePoolId = 2240b7ff-3dad-4568-90d1-853d196c6e11, ignoreFailoverLimit = false), log id: 6292538a
>2013-11-21 15:12:18,482 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HsmGetIsoListVDSCommand] (pool-6-thread-49) START, HsmGetIsoListVDSCommand(HostName = test4, HostId = 0b4c0615-8f00-43c5-b306-4db8f1dfb1fd, storagePoolId=2240b7ff-3dad-4568-90d1-853d196c6e11), log id: 2624f494
>2013-11-21 15:12:18,496 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-6-thread-50) Correlation ID: 18560e3, Call Stack: null, Custom Event ID: -1, Message: Storage Domain ISO_DOM (Data Center test4) was activated by admin@internal
>2013-11-21 15:12:18,513 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-6-thread-50) Correlation ID: 74bfad75, Job ID: c090db78-df63-4f11-8a47-72bc9c9cb0ba, Call Stack: null, Custom Event ID: -1, Message: Storage Domain ISO_DOM was attached to Data Center test4 by admin@internal
>2013-11-21 15:12:18,550 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HsmGetIsoListVDSCommand] (pool-6-thread-49) FINISH, HsmGetIsoListVDSCommand, return: [ubuntu-12.04.3-server-amd64.iso, CentOS-6.4-x86_64-minimal.iso, debian-7.2.0-amd64-CD-1.iso, CentOS-6.4-x86_64-netinstall.iso, Fedora-Live-XFCE-x86_64-19-1.iso, ubuntu-13.10-server-amd64.iso, debian-7.2.0-amd64-netinst.iso], log id: 2624f494
>2013-11-21 15:12:18,550 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetIsoListVDSCommand] (pool-6-thread-49) FINISH, GetIsoListVDSCommand, return: [ubuntu-12.04.3-server-amd64.iso, CentOS-6.4-x86_64-minimal.iso, debian-7.2.0-amd64-CD-1.iso, CentOS-6.4-x86_64-netinstall.iso, Fedora-Live-XFCE-x86_64-19-1.iso, ubuntu-13.10-server-amd64.iso, debian-7.2.0-amd64-netinst.iso], log id: 6292538a
>2013-11-21 15:12:18,566 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetFloppyListVDSCommand] (pool-6-thread-49) START, GetFloppyListVDSCommand( storagePoolId = 2240b7ff-3dad-4568-90d1-853d196c6e11, ignoreFailoverLimit = false), log id: 5d030e9c
>2013-11-21 15:12:18,599 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetFloppyListVDSCommand] (pool-6-thread-49) FINISH, GetFloppyListVDSCommand, return: [], log id: 5d030e9c
>2013-11-21 15:21:13,827 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-4) [2fcf84fb] Lock Acquired to object EngineLock [exclusiveLocks= key: faff3bf2-b15c-476b-bd92-c8c746282704 value: VM
>, sharedLocks= ]
>2013-11-21 15:21:13,832 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-4) [2fcf84fb] START, IsVmDuringInitiatingVDSCommand( vmId = faff3bf2-b15c-476b-bd92-c8c746282704), log id: 3400ce42
>2013-11-21 15:21:13,833 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-4) [2fcf84fb] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 3400ce42
>2013-11-21 15:21:13,892 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-6-thread-48) [2fcf84fb] Running command: RunVmCommand internal: false. Entities affected :  ID: faff3bf2-b15c-476b-bd92-c8c746282704 Type: VM
>2013-11-21 15:21:13,919 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (pool-6-thread-48) [2fcf84fb] START, IsoPrefixVDSCommand(HostName = test4, HostId = 0b4c0615-8f00-43c5-b306-4db8f1dfb1fd, storagePoolId=2240b7ff-3dad-4568-90d1-853d196c6e11), log id: e7b5076
>2013-11-21 15:21:13,934 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (pool-6-thread-48) [2fcf84fb] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/management:_var_lib_exports_iso/27945043-e2a8-4c1f-bd6b-bf39ba2a6d97/images/11111111-1111-1111-1111-111111111111, log id: e7b5076
>2013-11-21 15:21:13,951 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-6-thread-48) [2fcf84fb] START, CreateVmVDSCommand(HostName = test4, HostId = 0b4c0615-8f00-43c5-b306-4db8f1dfb1fd, vmId=faff3bf2-b15c-476b-bd92-c8c746282704, vm=VM [test4vm]), log id: 39b70b06
>2013-11-21 15:21:13,965 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-6-thread-48) [2fcf84fb] START, CreateVDSCommand(HostName = test4, HostId = 0b4c0615-8f00-43c5-b306-4db8f1dfb1fd, vmId=faff3bf2-b15c-476b-bd92-c8c746282704, vm=VM [test4vm]), log id: 354243b1
>2013-11-21 15:21:14,013 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-6-thread-48) [2fcf84fb] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=2048,kvmEnable=true,smp=2,vmType=kvm,emulatedMachine=pc-1.0,keyboardLayout=de,memGuaranteedSize=2048,nice=0,display=vnc,smartcardEnable=false,tabletEnable=true,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,timeOffset=0,transparentHugePages=true,vmId=faff3bf2-b15c-476b-bd92-c8c746282704,devices=[Ljava.util.HashMap;@53b3e7f3,acpiEnable=true,vmName=test4vm,cpuType=Nehalem,custom={}
>2013-11-21 15:21:14,015 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-6-thread-48) [2fcf84fb] FINISH, CreateVDSCommand, log id: 354243b1
>2013-11-21 15:21:14,034 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-6-thread-48) [2fcf84fb] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 39b70b06
>2013-11-21 15:21:14,035 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-6-thread-48) [2fcf84fb] Lock freed to object EngineLock [exclusiveLocks= key: faff3bf2-b15c-476b-bd92-c8c746282704 value: VM
>, sharedLocks= ]
>2013-11-21 15:21:14,043 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-6-thread-48) [2fcf84fb] Correlation ID: 2fcf84fb, Job ID: bcc24c17-d546-4409-afa3-15f86cae2dab, Call Stack: null, Custom Event ID: -1, Message: VM test4vm was started by admin@internal (Host: test4).
>2013-11-21 15:21:16,976 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-65) START, DestroyVDSCommand(HostName = test4, HostId = 0b4c0615-8f00-43c5-b306-4db8f1dfb1fd, vmId=faff3bf2-b15c-476b-bd92-c8c746282704, force=false, secondsToWait=0, gracefully=false), log id: 33da5bae
>2013-11-21 15:21:17,140 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-65) FINISH, DestroyVDSCommand, log id: 33da5bae
>2013-11-21 15:21:17,152 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-65) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VM test4vm is down. Exit message: Child quit during startup handshake: Input/output error.
>2013-11-21 15:21:17,153 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-65) Running on vds during rerun failed vm: null
>2013-11-21 15:21:17,154 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-65) vm test4vm running in db and not running in vds - add to rerun treatment. vds test4
>2013-11-21 15:21:17,156 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-65) START, FullListVdsCommand(HostName = test4, HostId = 0b4c0615-8f00-43c5-b306-4db8f1dfb1fd, vds=Host[test4], vmIds=[faff3bf2-b15c-476b-bd92-c8c746282704]), log id: b380b2
>2013-11-21 15:21:17,159 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-65) FINISH, FullListVdsCommand, return: [Ljava.util.HashMap;@8c6267a, log id: b380b2
>2013-11-21 15:21:17,186 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-65) Rerun vm faff3bf2-b15c-476b-bd92-c8c746282704. Called from vds test4
>2013-11-21 15:21:17,202 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-6-thread-48) Correlation ID: 2fcf84fb, Job ID: bcc24c17-d546-4409-afa3-15f86cae2dab, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM test4vm on Host test4.
>2013-11-21 15:21:17,219 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-6-thread-48) Lock Acquired to object EngineLock [exclusiveLocks= key: faff3bf2-b15c-476b-bd92-c8c746282704 value: VM
>, sharedLocks= ]
>2013-11-21 15:21:17,222 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (pool-6-thread-48) START, IsVmDuringInitiatingVDSCommand( vmId = faff3bf2-b15c-476b-bd92-c8c746282704), log id: 242c9228
>2013-11-21 15:21:17,223 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (pool-6-thread-48) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 242c9228
>2013-11-21 15:21:17,234 WARN  [org.ovirt.engine.core.bll.RunVmCommand] (pool-6-thread-48) CanDoAction of action RunVm failed. Reasons:VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM
>2013-11-21 15:21:17,235 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-6-thread-48) Lock freed to object EngineLock [exclusiveLocks= key: faff3bf2-b15c-476b-bd92-c8c746282704 value: VM
>, sharedLocks= ]
>2013-11-21 15:21:17,261 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-6-thread-48) Correlation ID: 2fcf84fb, Job ID: bcc24c17-d546-4409-afa3-15f86cae2dab, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM test4vm (User: admin@internal).
>2013-11-21 15:21:39,316 INFO  [org.ovirt.engine.core.bll.UpdateVmCommand] (ajp--127.0.0.1-8702-5) Lock Acquired to object EngineLock [exclusiveLocks= key: test4vm value: VM_NAME
>, sharedLocks= ]
>2013-11-21 15:21:39,336 INFO  [org.ovirt.engine.core.bll.UpdateVmCommand] (ajp--127.0.0.1-8702-5) Running command: UpdateVmCommand internal: false. Entities affected :  ID: faff3bf2-b15c-476b-bd92-c8c746282704 Type: VM
>2013-11-21 15:21:39,369 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-5) Correlation ID: 3597467e, Job ID: d6a0215d-581b-4f92-9a32-f13ac8de08b2, Call Stack: null, Custom Event ID: -1, Message: VM test4vm configuration was updated by admin@internal.
>2013-11-21 15:21:39,377 INFO  [org.ovirt.engine.core.bll.UpdateVmCommand] (ajp--127.0.0.1-8702-5) Lock freed to object EngineLock [exclusiveLocks= key: test4vm value: VM_NAME
>, sharedLocks= ]
>2013-11-21 15:21:39,416 INFO  [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp--127.0.0.1-8702-7) MultipleActionsRunner of type AddVmInterface invoked with no actions
>2013-11-21 15:21:39,430 INFO  [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp--127.0.0.1-8702-9) MultipleActionsRunner of type UpdateVmInterface invoked with no actions
>2013-11-21 15:21:39,445 INFO  [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp--127.0.0.1-8702-4) MultipleActionsRunner of type RemoveVmInterface invoked with no actions
>2013-11-21 15:34:26,745 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStatsVDSCommand] (DefaultQuartzScheduler_Worker-44) Command GetStatsVDS execution failed. Exception: VDSNetworkException: java.net.SocketTimeoutException: connect timed out
>2013-11-21 15:34:26,745 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-44) vds::refreshVdsStats Failed getVdsStats,  vds = 9b4bbf22-0655-4b0a-b792-3cdca91d5b6b : test3, error = VDSNetworkException: java.net.SocketTimeoutException: connect timed out
>2013-11-21 15:34:26,756 WARN  [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-44) Failed to refresh VDS , vds = 9b4bbf22-0655-4b0a-b792-3cdca91d5b6b : test3, VDS Network Error, continuing.
>java.net.SocketTimeoutException: connect timed out
>2013-11-21 15:34:27,901 INFO  [org.ovirt.engine.core.bll.UpdateVmCommand] (ajp--127.0.0.1-8702-6) Lock Acquired to object EngineLock [exclusiveLocks= key: test4vm value: VM_NAME
>, sharedLocks= ]
>2013-11-21 15:34:27,914 INFO  [org.ovirt.engine.core.bll.UpdateVmCommand] (ajp--127.0.0.1-8702-6) Running command: UpdateVmCommand internal: false. Entities affected :  ID: faff3bf2-b15c-476b-bd92-c8c746282704 Type: VM
>2013-11-21 15:34:27,931 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-6) Correlation ID: 5e3b59ab, Job ID: 67901871-d91c-4a9f-8750-34618bd2eede, Call Stack: null, Custom Event ID: -1, Message: VM test4vm configuration was updated by admin@internal.
>2013-11-21 15:34:27,940 INFO  [org.ovirt.engine.core.bll.UpdateVmCommand] (ajp--127.0.0.1-8702-6) Lock freed to object EngineLock [exclusiveLocks= key: test4vm value: VM_NAME
>, sharedLocks= ]
>2013-11-21 15:34:27,985 INFO  [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp--127.0.0.1-8702-9) MultipleActionsRunner of type AddVmInterface invoked with no actions
>2013-11-21 15:34:27,999 INFO  [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp--127.0.0.1-8702-11) MultipleActionsRunner of type UpdateVmInterface invoked with no actions
>2013-11-21 15:34:28,017 INFO  [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp--127.0.0.1-8702-4) MultipleActionsRunner of type RemoveVmInterface invoked with no actions
>2013-11-21 15:34:30,764 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-8) [6af2b540] Lock Acquired to object EngineLock [exclusiveLocks= key: faff3bf2-b15c-476b-bd92-c8c746282704 value: VM
>, sharedLocks= ]
>2013-11-21 15:34:30,768 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-8) [6af2b540] START, IsVmDuringInitiatingVDSCommand( vmId = faff3bf2-b15c-476b-bd92-c8c746282704), log id: 2a6e9061
>2013-11-21 15:34:30,769 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-8) [6af2b540] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 2a6e9061
>2013-11-21 15:34:30,823 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-6-thread-49) [6af2b540] Running command: RunVmCommand internal: false. Entities affected :  ID: faff3bf2-b15c-476b-bd92-c8c746282704 Type: VM
>2013-11-21 15:34:30,841 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (pool-6-thread-49) [6af2b540] START, IsoPrefixVDSCommand(HostName = test4, HostId = 0b4c0615-8f00-43c5-b306-4db8f1dfb1fd, storagePoolId=2240b7ff-3dad-4568-90d1-853d196c6e11), log id: 496992fc
>2013-11-21 15:34:30,841 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (pool-6-thread-49) [6af2b540] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/management:_var_lib_exports_iso/27945043-e2a8-4c1f-bd6b-bf39ba2a6d97/images/11111111-1111-1111-1111-111111111111, log id: 496992fc
>2013-11-21 15:34:30,843 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-6-thread-49) [6af2b540] START, CreateVmVDSCommand(HostName = test4, HostId = 0b4c0615-8f00-43c5-b306-4db8f1dfb1fd, vmId=faff3bf2-b15c-476b-bd92-c8c746282704, vm=VM [test4vm]), log id: 201b326a
>2013-11-21 15:34:30,846 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-6-thread-49) [6af2b540] START, CreateVDSCommand(HostName = test4, HostId = 0b4c0615-8f00-43c5-b306-4db8f1dfb1fd, vmId=faff3bf2-b15c-476b-bd92-c8c746282704, vm=VM [test4vm]), log id: 6180f32f
>2013-11-21 15:34:30,918 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStatusVDSCommand] (DefaultQuartzScheduler_Worker-52) Command SpmStatusVDS execution failed. Exception: VDSNetworkException: java.net.SocketTimeoutException: connect timed out
>2013-11-21 15:34:30,919 INFO  [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (DefaultQuartzScheduler_Worker-52) Running command: SetStoragePoolStatusCommand internal: true. Entities affected :  ID: a555f4aa-d962-425c-91c1-6ee5c4da4a23 Type: StoragePool
>2013-11-21 15:34:30,948 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-52) Correlation ID: 77ba6427, Call Stack: null, Custom Event ID: -1, Message: Invalid status on Data Center localcenter1. Setting Data Center status to Non Responsive (On host test3, Error: Network error during communication with the Host.).
>2013-11-21 15:34:30,987 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-6-thread-49) [6af2b540] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=2048,kvmEnable=true,smp=2,vmType=kvm,emulatedMachine=pc-1.0,keyboardLayout=de,memGuaranteedSize=2048,pitReinjection=false,nice=0,display=vnc,smartcardEnable=false,tabletEnable=true,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,timeOffset=0,transparentHugePages=true,vmId=faff3bf2-b15c-476b-bd92-c8c746282704,devices=[Ljava.util.HashMap;@32661915,acpiEnable=true,vmName=test4vm,cpuType=Nehalem,custom={}
>2013-11-21 15:34:30,988 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-6-thread-49) [6af2b540] FINISH, CreateVDSCommand, log id: 6180f32f
>2013-11-21 15:34:31,007 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-6-thread-49) [6af2b540] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 201b326a
>2013-11-21 15:34:31,007 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-6-thread-49) [6af2b540] Lock freed to object EngineLock [exclusiveLocks= key: faff3bf2-b15c-476b-bd92-c8c746282704 value: VM
>, sharedLocks= ]
>2013-11-21 15:34:31,015 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-6-thread-49) [6af2b540] Correlation ID: 6af2b540, Job ID: 6b4fd772-49fe-435f-b1a9-5eb6f0a9b41b, Call Stack: null, Custom Event ID: -1, Message: VM test4vm was started by admin@internal (Host: test4).
>2013-11-21 15:34:31,775 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-53) Command GetCapabilitiesVDS execution failed. Exception: VDSNetworkException: java.net.SocketTimeoutException: connect timed out
>2013-11-21 15:34:31,776 WARN  [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-53) Failed to refresh VDS , vds = 9b4bbf22-0655-4b0a-b792-3cdca91d5b6b : test3, VDS Network Error, continuing.
>java.net.SocketTimeoutException: connect timed out
>2013-11-21 15:34:32,952 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (DefaultQuartzScheduler_Worker-52) IrsBroker::Failed::GetStoragePoolInfoVDS due to: SocketTimeoutException: connect timed out
>2013-11-21 15:34:33,845 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-56) START, DestroyVDSCommand(HostName = test4, HostId = 0b4c0615-8f00-43c5-b306-4db8f1dfb1fd, vmId=faff3bf2-b15c-476b-bd92-c8c746282704, force=false, secondsToWait=0, gracefully=false), log id: 6fd6d5c4
>2013-11-21 15:34:34,004 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-56) FINISH, DestroyVDSCommand, log id: 6fd6d5c4
>2013-11-21 15:34:34,015 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-56) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VM test4vm is down. Exit message: Child quit during startup handshake: Input/output error.
>2013-11-21 15:34:34,015 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-56) Running on vds during rerun failed vm: null
>2013-11-21 15:34:34,016 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-56) vm test4vm running in db and not running in vds - add to rerun treatment. vds test4
>2013-11-21 15:34:34,057 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-56) Rerun vm faff3bf2-b15c-476b-bd92-c8c746282704. Called from vds test4
>2013-11-21 15:34:34,073 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-6-thread-50) Correlation ID: 6af2b540, Job ID: 6b4fd772-49fe-435f-b1a9-5eb6f0a9b41b, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM test4vm on Host test4.
>2013-11-21 15:34:34,090 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-6-thread-50) Lock Acquired to object EngineLock [exclusiveLocks= key: faff3bf2-b15c-476b-bd92-c8c746282704 value: VM
>, sharedLocks= ]
>2013-11-21 15:34:34,093 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (pool-6-thread-50) START, IsVmDuringInitiatingVDSCommand( vmId = faff3bf2-b15c-476b-bd92-c8c746282704), log id: 4a4a7ddc
>2013-11-21 15:34:34,094 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (pool-6-thread-50) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 4a4a7ddc
>2013-11-21 15:34:34,106 WARN  [org.ovirt.engine.core.bll.RunVmCommand] (pool-6-thread-50) CanDoAction of action RunVm failed. Reasons:VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM
>2013-11-21 15:34:34,106 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-6-thread-50) Lock freed to object EngineLock [exclusiveLocks= key: faff3bf2-b15c-476b-bd92-c8c746282704 value: VM
>, sharedLocks= ]
>2013-11-21 15:34:34,131 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-6-thread-50) Correlation ID: 6af2b540, Job ID: 6b4fd772-49fe-435f-b1a9-5eb6f0a9b41b, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM test4vm (User: admin@internal).

Actions: View

Attachments on bug 1033064: 827209 | 827738