Attachment 651738 Details for Bug 872506 – full engine's log

full engine's log

engine.log (text/plain), 24.85 KB, created by Geyang Kong on 2012-11-26 04:27:22 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Geyang Kong

Created: 2012-11-26 04:27:22 UTC

Size: 24.85 KB

patch

obsolete

>2012-11-26 12:23:11,693 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-12) START, GetVmsInfoVDSCommand( storagePoolId = 794be62d-1be7-4881-a8bb-a95d77853247, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 08552000-7ab1-4a4a-81f7-39fdcf785f68, vmIdList = null), log id: 66ec4fde
>2012-11-26 12:23:12,018 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-12) FINISH, GetVmsInfoVDSCommand, log id: 66ec4fde
>2012-11-26 12:23:20,710 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-21) START, GetVmsInfoVDSCommand( storagePoolId = 794be62d-1be7-4881-a8bb-a95d77853247, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 08552000-7ab1-4a4a-81f7-39fdcf785f68, vmIdList = null), log id: 4bc4025c
>2012-11-26 12:23:21,421 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-21) FINISH, GetVmsInfoVDSCommand, log id: 4bc4025c
>2012-11-26 12:23:22,730 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageDomainsListVDSCommand] (ajp-/127.0.0.1:8702-21) START, GetImageDomainsListVDSCommand( storagePoolId = 794be62d-1be7-4881-a8bb-a95d77853247, ignoreFailoverLimit = false, compatabilityVersion = null, imageGroupId = 30a45932-243f-461d-87f5-d9065adc9793), log id: 1d0c999b
>2012-11-26 12:23:23,633 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageDomainsListVDSCommand] (ajp-/127.0.0.1:8702-21) FINISH, GetImageDomainsListVDSCommand, return: [], log id: 1d0c999b
>2012-11-26 12:23:23,634 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageDomainsListVDSCommand] (ajp-/127.0.0.1:8702-21) START, GetImageDomainsListVDSCommand( storagePoolId = 794be62d-1be7-4881-a8bb-a95d77853247, ignoreFailoverLimit = false, compatabilityVersion = null, imageGroupId = be3b21fc-2c26-41b5-8366-698b750928e8), log id: 26389e03
>2012-11-26 12:23:23,714 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageDomainsListVDSCommand] (ajp-/127.0.0.1:8702-21) FINISH, GetImageDomainsListVDSCommand, return: [], log id: 26389e03
>2012-11-26 12:23:23,715 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DoesImageExistVDSCommand] (ajp-/127.0.0.1:8702-21) START, DoesImageExistVDSCommand( storagePoolId = 794be62d-1be7-4881-a8bb-a95d77853247, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 08552000-7ab1-4a4a-81f7-39fdcf785f68, imageGroupId = be3b21fc-2c26-41b5-8366-698b750928e8, imageId = 4ea1f996-3b1b-4f23-b020-61a4ac25db39), log id: 4f0dea8f
>2012-11-26 12:23:23,888 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DoesImageExistVDSCommand] (ajp-/127.0.0.1:8702-21) FINISH, DoesImageExistVDSCommand, return: true, log id: 4f0dea8f
>2012-11-26 12:23:23,889 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DoesImageExistVDSCommand] (ajp-/127.0.0.1:8702-21) START, DoesImageExistVDSCommand( storagePoolId = 794be62d-1be7-4881-a8bb-a95d77853247, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 08552000-7ab1-4a4a-81f7-39fdcf785f68, imageGroupId = 30a45932-243f-461d-87f5-d9065adc9793, imageId = f80574c4-8ec2-4837-92b4-2d7f8651b57b), log id: 1aa27ad5
>2012-11-26 12:23:24,113 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DoesImageExistVDSCommand] (ajp-/127.0.0.1:8702-21) FINISH, DoesImageExistVDSCommand, return: true, log id: 1aa27ad5
>2012-11-26 12:23:24,564 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-8) START, GetVmsInfoVDSCommand( storagePoolId = 794be62d-1be7-4881-a8bb-a95d77853247, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 08552000-7ab1-4a4a-81f7-39fdcf785f68, vmIdList = null), log id: 563faad5
>2012-11-26 12:23:24,629 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp-/127.0.0.1:8702-8) FINISH, GetVmsInfoVDSCommand, log id: 563faad5
>2012-11-26 12:23:26,727 INFO  [org.ovirt.engine.core.bll.ImportVmCommand] (pool-4-thread-50) [5432fd85] Running command: ImportVmCommand internal: false. Entities affected :  ID: 6f9431a8-e3fa-4081-af58-a34f532692d7 Type: Storage,  ID: 6f9431a8-e3fa-4081-af58-a34f532692d7 Type: Storage
>2012-11-26 12:23:35,409 INFO  [org.ovirt.engine.core.bll.MoveOrCopyImageGroupCommand] (pool-4-thread-50) [5121e1d] Running command: MoveOrCopyImageGroupCommand internal: true. Entities affected :  ID: 6f9431a8-e3fa-4081-af58-a34f532692d7 Type: Storage
>2012-11-26 12:23:35,423 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.MoveImageGroupVDSCommand] (pool-4-thread-50) [5121e1d] START, MoveImageGroupVDSCommand( storagePoolId = 794be62d-1be7-4881-a8bb-a95d77853247, ignoreFailoverLimit = false, compatabilityVersion = 3.0, storageDomainId = 08552000-7ab1-4a4a-81f7-39fdcf785f68, imageGroupId = be3b21fc-2c26-41b5-8366-698b750928e8, dstDomainId = 6f9431a8-e3fa-4081-af58-a34f532692d7, vmId = d3532d71-9a78-4c92-9a2a-8ed4e89c131d, op = Copy, postZero = false, force = true), log id: 448926a5
>2012-11-26 12:23:35,663 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.MoveImageGroupVDSCommand] (pool-4-thread-50) [5121e1d] FINISH, MoveImageGroupVDSCommand, log id: 448926a5
>2012-11-26 12:23:35,975 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-50) [5121e1d] EntityAsyncTask::Adding EntityMultiAsyncTasks object for entity f80574c4-8ec2-4837-92b4-2d7f8651b57b
>2012-11-26 12:23:35,975 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (pool-4-thread-50) [5121e1d] EntityMultiAsyncTasks::AttachTask: Attaching task d34215c5-20c6-478a-b1c4-a6a2a023b1e1 to entity f80574c4-8ec2-4837-92b4-2d7f8651b57b.
>2012-11-26 12:23:36,182 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskUtils] (pool-4-thread-50) [5121e1d] Adding task d34215c5-20c6-478a-b1c4-a6a2a023b1e1 to DataBase
>2012-11-26 12:23:37,943 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (pool-4-thread-50) [5121e1d] Adding task d34215c5-20c6-478a-b1c4-a6a2a023b1e1 (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2012-11-26 12:23:38,289 INFO  [org.ovirt.engine.core.bll.MoveOrCopyImageGroupCommand] (pool-4-thread-50) [63b5c249] Running command: MoveOrCopyImageGroupCommand internal: true. Entities affected :  ID: 6f9431a8-e3fa-4081-af58-a34f532692d7 Type: Storage
>2012-11-26 12:23:38,296 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.MoveImageGroupVDSCommand] (pool-4-thread-50) [63b5c249] START, MoveImageGroupVDSCommand( storagePoolId = 794be62d-1be7-4881-a8bb-a95d77853247, ignoreFailoverLimit = false, compatabilityVersion = 3.0, storageDomainId = 08552000-7ab1-4a4a-81f7-39fdcf785f68, imageGroupId = 30a45932-243f-461d-87f5-d9065adc9793, dstDomainId = 6f9431a8-e3fa-4081-af58-a34f532692d7, vmId = d3532d71-9a78-4c92-9a2a-8ed4e89c131d, op = Copy, postZero = false, force = true), log id: 186c911a
>2012-11-26 12:23:39,188 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.MoveImageGroupVDSCommand] (pool-4-thread-50) [63b5c249] FINISH, MoveImageGroupVDSCommand, log id: 186c911a
>2012-11-26 12:23:39,234 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-50) [63b5c249] EntityAsyncTask::Adding EntityMultiAsyncTasks object for entity 4ea1f996-3b1b-4f23-b020-61a4ac25db39
>2012-11-26 12:23:39,234 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (pool-4-thread-50) [63b5c249] EntityMultiAsyncTasks::AttachTask: Attaching task f2f87f20-cbff-49e8-bf72-714b01ea1ee0 to entity 4ea1f996-3b1b-4f23-b020-61a4ac25db39.
>2012-11-26 12:23:39,238 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskUtils] (pool-4-thread-50) [63b5c249] Adding task f2f87f20-cbff-49e8-bf72-714b01ea1ee0 to DataBase
>2012-11-26 12:23:40,210 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (pool-4-thread-50) [63b5c249] Adding task f2f87f20-cbff-49e8-bf72-714b01ea1ee0 (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2012-11-26 12:23:40,855 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-4-thread-50) [63b5c249] START, SetVmStatusVDSCommand( vmId = d3532d71-9a78-4c92-9a2a-8ed4e89c131d, status = ImageLocked), log id: 5416cf7b
>2012-11-26 12:23:40,999 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-4-thread-50) [63b5c249] FINISH, SetVmStatusVDSCommand, log id: 5416cf7b
>2012-11-26 12:23:41,334 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-4-thread-50) [63b5c249] BaseAsyncTask::StartPollingTask: Starting to poll task d34215c5-20c6-478a-b1c4-a6a2a023b1e1.
>2012-11-26 12:23:41,334 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (pool-4-thread-50) [63b5c249] EntityMultiAsyncTasks::StartPollingTask: Current Action Type for entity f80574c4-8ec2-4837-92b4-2d7f8651b57b is ImportVm (determined by task d34215c5-20c6-478a-b1c4-a6a2a023b1e1)
>2012-11-26 12:23:41,334 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-4-thread-50) [63b5c249] BaseAsyncTask::StartPollingTask: Starting to poll task f2f87f20-cbff-49e8-bf72-714b01ea1ee0.
>2012-11-26 12:23:41,334 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (pool-4-thread-50) [63b5c249] EntityMultiAsyncTasks::StartPollingTask: Current Action Type for entity 4ea1f996-3b1b-4f23-b020-61a4ac25db39 is ImportVm (determined by task f2f87f20-cbff-49e8-bf72-714b01ea1ee0)
>2012-11-26 12:23:45,386 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-20) Polling and updating Async Tasks: 2 tasks, 2 tasks to poll now
>2012-11-26 12:23:45,622 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-20) SPMAsyncTask::PollTask: Polling task f2f87f20-cbff-49e8-bf72-714b01ea1ee0 (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status running.
>2012-11-26 12:23:45,622 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-20) SPMAsyncTask::PollTask: Polling task d34215c5-20c6-478a-b1c4-a6a2a023b1e1 (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status running.
>2012-11-26 12:23:45,622 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-20) Finished polling Tasks, will poll again in 10 seconds.
>2012-11-26 12:24:36,979 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-21) SPMAsyncTask::PollTask: Polling task f2f87f20-cbff-49e8-bf72-714b01ea1ee0 (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2012-11-26 12:24:37,022 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-21) BaseAsyncTask::OnTaskEndSuccess: Task f2f87f20-cbff-49e8-bf72-714b01ea1ee0 (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2012-11-26 12:24:37,023 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-21) EntityAsyncTask::EndActionIfNecessary: All tasks of entity 4ea1f996-3b1b-4f23-b020-61a4ac25db39 has ended -> executing EndAction
>2012-11-26 12:24:37,023 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-21) EntityAsyncTask::EndAction: Ending action for 1 tasks (entity ID: 4ea1f996-3b1b-4f23-b020-61a4ac25db39): calling EndAction for action type ImportVm.
>2012-11-26 12:24:37,024 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-50) EntityAsyncTask::EndCommandAction [within thread] context: Attempting to EndAction ImportVm, executionIndex: 0
>2012-11-26 12:24:37,097 INFO  [org.ovirt.engine.core.bll.ImportVmCommand] (pool-4-thread-50) Ending command successfully: org.ovirt.engine.core.bll.ImportVmCommand
>2012-11-26 12:24:37,158 INFO  [org.ovirt.engine.core.bll.MoveOrCopyImageGroupCommand] (pool-4-thread-50) [5121e1d] Ending command successfully: org.ovirt.engine.core.bll.MoveOrCopyImageGroupCommand
>2012-11-26 12:24:37,175 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-4-thread-50) [5121e1d] START, GetImageInfoVDSCommand( storagePoolId = 794be62d-1be7-4881-a8bb-a95d77853247, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 6f9431a8-e3fa-4081-af58-a34f532692d7, imageGroupId = 30a45932-243f-461d-87f5-d9065adc9793, imageId = f80574c4-8ec2-4837-92b4-2d7f8651b57b), log id: ea2c09f
>2012-11-26 12:24:37,558 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-4-thread-50) [5121e1d] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@351b74ab, log id: ea2c09f
>2012-11-26 12:24:37,666 INFO  [org.ovirt.engine.core.bll.MoveOrCopyImageGroupCommand] (pool-4-thread-50) [63b5c249] Ending command successfully: org.ovirt.engine.core.bll.MoveOrCopyImageGroupCommand
>2012-11-26 12:24:37,674 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-4-thread-50) [63b5c249] START, GetImageInfoVDSCommand( storagePoolId = 794be62d-1be7-4881-a8bb-a95d77853247, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 6f9431a8-e3fa-4081-af58-a34f532692d7, imageGroupId = be3b21fc-2c26-41b5-8366-698b750928e8, imageId = 4ea1f996-3b1b-4f23-b020-61a4ac25db39), log id: 7e70fa33
>2012-11-26 12:24:38,078 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-4-thread-50) [63b5c249] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@230968cc, log id: 7e70fa33
>2012-11-26 12:24:38,212 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-4-thread-50) START, SetVmStatusVDSCommand( vmId = d3532d71-9a78-4c92-9a2a-8ed4e89c131d, status = Down), log id: 6f7bdb1d
>2012-11-26 12:24:38,225 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-4-thread-50) FINISH, SetVmStatusVDSCommand, log id: 6f7bdb1d
>2012-11-26 12:24:38,554 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (pool-4-thread-50) START, UpdateVMVDSCommand( storagePoolId = 794be62d-1be7-4881-a8bb-a95d77853247, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 00000000-0000-0000-0000-000000000000, infoDictionary.size = 1), log id: 3ba77723
>2012-11-26 12:24:38,818 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (pool-4-thread-50) FINISH, UpdateVMVDSCommand, log id: 3ba77723
>2012-11-26 12:24:38,964 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-50) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type ImportVm completed, handling the result.
>2012-11-26 12:24:38,964 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-50) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type ImportVm succeeded, clearing tasks.
>2012-11-26 12:24:39,071 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-4-thread-50) SPMAsyncTask::ClearAsyncTask: Attempting to clear task f2f87f20-cbff-49e8-bf72-714b01ea1ee0
>2012-11-26 12:24:39,072 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-4-thread-50) START, SPMClearTaskVDSCommand( storagePoolId = 794be62d-1be7-4881-a8bb-a95d77853247, ignoreFailoverLimit = false, compatabilityVersion = null, taskId = f2f87f20-cbff-49e8-bf72-714b01ea1ee0), log id: 32c67b0e
>2012-11-26 12:24:39,078 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-4-thread-50) START, HSMClearTaskVDSCommand(HostName = dell-op780-04.qe.lab.eng.nay.redhat.com, HostId = 9a54a880-339e-11e2-93ca-00237d53abab, taskId=f2f87f20-cbff-49e8-bf72-714b01ea1ee0), log id: 6684998b
>2012-11-26 12:24:39,494 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-4-thread-50) FINISH, HSMClearTaskVDSCommand, log id: 6684998b
>2012-11-26 12:24:39,494 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-4-thread-50) FINISH, SPMClearTaskVDSCommand, log id: 32c67b0e
>2012-11-26 12:24:39,554 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-4-thread-50) BaseAsyncTask::RemoveTaskFromDB: Removed task f2f87f20-cbff-49e8-bf72-714b01ea1ee0 from DataBase
>2012-11-26 12:24:39,554 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-50) EntityAsyncTask::HandleEndActionResult [within thread]: Removing EntityMultiAsyncTasks object for entity 4ea1f996-3b1b-4f23-b020-61a4ac25db39
>2012-11-26 12:24:58,182 INFO  [org.ovirt.engine.core.bll.UpdateVmInterfaceCommand] (ajp-/127.0.0.1:8702-6) [5aa90f4b] Running command: UpdateVmInterfaceCommand internal: false. Entities affected :  ID: d3532d71-9a78-4c92-9a2a-8ed4e89c131d Type: VM
>2012-11-26 12:25:00,001 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-18) Checking autorecoverable hosts
>2012-11-26 12:25:00,003 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-18) Autorecovering 0 hosts
>2012-11-26 12:25:00,004 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-18) Checking autorecoverable hosts done
>2012-11-26 12:25:00,004 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-18) Autorecovering storage domains is disabled, skipping
>2012-11-26 12:25:01,524 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp-/127.0.0.1:8702-23) Lock Acquired to object EngineLock [exclusiveLocks= key: d3532d71-9a78-4c92-9a2a-8ed4e89c131d value: VM
>, sharedLocks= ]
>2012-11-26 12:25:01,534 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8702-23) START, IsValidVDSCommand( storagePoolId = 794be62d-1be7-4881-a8bb-a95d77853247, ignoreFailoverLimit = false, compatabilityVersion = null), log id: d0eeafe
>2012-11-26 12:25:01,535 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8702-23) FINISH, IsValidVDSCommand, return: true, log id: d0eeafe
>2012-11-26 12:25:01,664 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8702-23) START, IsVmDuringInitiatingVDSCommand( vmId = d3532d71-9a78-4c92-9a2a-8ed4e89c131d), log id: 5215406b
>2012-11-26 12:25:01,664 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8702-23) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 5215406b
>2012-11-26 12:25:03,019 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-50) [744e84c8] Running command: RunVmCommand internal: false. Entities affected :  ID: d3532d71-9a78-4c92-9a2a-8ed4e89c131d Type: VM
>2012-11-26 12:25:03,212 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-50) [744e84c8] START, CreateVmVDSCommand(HostName = dell-op780-04.qe.lab.eng.nay.redhat.com, HostId = 9a54a880-339e-11e2-93ca-00237d53abab, vmId=d3532d71-9a78-4c92-9a2a-8ed4e89c131d, vm=org.ovirt.engine.core.common.businessentities.VM@6b8191bf), log id: 77a8b4d
>2012-11-26 12:25:03,428 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-50) [744e84c8] START, CreateVDSCommand(HostName = dell-op780-04.qe.lab.eng.nay.redhat.com, HostId = 9a54a880-339e-11e2-93ca-00237d53abab, vmId=d3532d71-9a78-4c92-9a2a-8ed4e89c131d, vm=org.ovirt.engine.core.common.businessentities.VM@6b8191bf), log id: 1324a334
>2012-11-26 12:25:03,428 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (pool-4-thread-50) [744e84c8] Failed in CreateVDS method, for vds: dell-op780-04.qe.lab.eng.nay.redhat.com; host: 10.66.72.26
>2012-11-26 12:25:03,429 ERROR [org.ovirt.engine.core.vdsbroker.VDSCommandBase] (pool-4-thread-50) [744e84c8] Command CreateVDS execution failed. Exception: NullPointerException: 
>2012-11-26 12:25:03,430 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-50) [744e84c8] FINISH, CreateVDSCommand, log id: 1324a334
>2012-11-26 12:25:03,430 ERROR [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-50) [744e84c8] Error in excuting CreateVmVDSCommand: java.lang.NullPointerException
>	at org.ovirt.engine.core.dal.comparators.DiskImageByDiskAliasComparator.compare(DiskImageByDiskAliasComparator.java:11) [engine-dal.jar:]
>	at org.ovirt.engine.core.dal.comparators.DiskImageByDiskAliasComparator.compare(DiskImageByDiskAliasComparator.java:7) [engine-dal.jar:]
>	at java.util.TimSort.countRunAndMakeAscending(TimSort.java:324) [rt.jar:1.7.0_03-icedtea]
>	at java.util.TimSort.sort(TimSort.java:189) [rt.jar:1.7.0_03-icedtea]
>	at java.util.TimSort.sort(TimSort.java:173) [rt.jar:1.7.0_03-icedtea]
>	at java.util.Arrays.sort(Arrays.java:659) [rt.jar:1.7.0_03-icedtea]
>	at java.util.Collections.sort(Collections.java:217) [rt.jar:1.7.0_03-icedtea]
>	at org.ovirt.engine.core.vdsbroker.vdsbroker.VmInfoBuilderBase.getSortedDisks(VmInfoBuilderBase.java:189) [engine-vdsbroker.jar:]
>	at org.ovirt.engine.core.vdsbroker.vdsbroker.VmOldInfoBuilder.buildVmDrives(VmOldInfoBuilder.java:57) [engine-vdsbroker.jar:]
>	at org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand.buildVmData(CreateVDSCommand.java:64) [engine-vdsbroker.jar:]
>	at org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand.ExecuteVdsBrokerCommand(CreateVDSCommand.java:24) [engine-vdsbroker.jar:]
>	at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.ExecuteVDSCommand(VdsBrokerCommand.java:91) [engine-vdsbroker.jar:]
>	at org.ovirt.engine.core.vdsbroker.VDSCommandBase.ExecuteCommand(VDSCommandBase.java:63) [engine-vdsbroker.jar:]
>	at org.ovirt.engine.core.dal.VdcCommandBase.Execute(VdcCommandBase.java:41) [engine-dal.jar:]
>	at org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand.ExecuteVdsIdCommand(CreateVmVDSCommand.java:65) [engine-vdsbroker.jar:]
>	at org.ovirt.engine.core.vdsbroker.VdsIdVDSCommandBase.ExecuteVDSCommand(VdsIdVDSCommandBase.java:49) [engine-vdsbroker.jar:]
>	at org.ovirt.engine.core.vdsbroker.VDSCommandBase.ExecuteCommand(VDSCommandBase.java:63) [engine-vdsbroker.jar:]
>	at org.ovirt.engine.core.dal.VdcCommandBase.Execute(VdcCommandBase.java:41) [engine-dal.jar:]
>	at org.ovirt.engine.core.vdsbroker.ResourceManager.runVdsCommand(ResourceManager.java:388) [engine-vdsbroker.jar:]
>	at org.ovirt.engine.core.bll.VDSBrokerFrontendImpl.RunVdsCommand(VDSBrokerFrontendImpl.java:33) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.VDSBrokerFrontendImpl.RunAsyncVdsCommand(VDSBrokerFrontendImpl.java:48) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.RunVmCommand.CreateVm(RunVmCommand.java:407) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.RunVmCommand.RunVm(RunVmCommand.java:201) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.RunVmCommand.ExecuteVmCommand(RunVmCommand.java:255) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.VmCommand.executeCommand(VmCommand.java:78) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:876) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:973) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1372) [engine-bll.jar:]
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:168) [engine-utils.jar:]
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:107) [engine-utils.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:991) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:291) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.MultipleActionsRunner.executeValidatedCommands(MultipleActionsRunner.java:182) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.MultipleActionsRunner.RunCommands(MultipleActionsRunner.java:162) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.SortedMultipleActionsRunnerBase.RunCommands(SortedMultipleActionsRunnerBase.java:16) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.MultipleActionsRunner$1.run(MultipleActionsRunner.java:84) [engine-bll.jar:]
>	at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:64) [engine-utils.jar:]
>	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_03-icedtea]
>	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) [rt.jar:1.7.0_03-icedtea]
>	at java.util.concurrent.FutureTask.run(FutureTask.java:166) [rt.jar:1.7.0_03-icedtea]
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) [rt.jar:1.7.0_03-icedtea]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) [rt.jar:1.7.0_03-icedtea]
>	at java.lang.Thread.run(Thread.java:722) [rt.jar:1.7.0_03-icedtea]
>
>2012-11-26 12:25:03,823 ERROR [org.ovirt.engine.core.vdsbroker.VDSCommandBase] (pool-4-thread-50) [744e84c8] Command CreateVmVDS execution failed. Exception: RuntimeException: java.lang.NullPointerException
>2012-11-26 12:25:03,823 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-50) [744e84c8] FINISH, CreateVmVDSCommand, log id: 77a8b4d
>2012-11-26 12:25:03,823 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-50) [744e84c8] Lock freed to object EngineLock [exclusiveLocks= key: d3532d71-9a78-4c92-9a2a-8ed4e89c131d value: VM
>, sharedLocks= ]
>2012-11-26 12:25:03,824 ERROR [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-50) [744e84c8] Command org.ovirt.engine.core.bll.RunVmCommand throw Vdc Bll exception. With error message VdcBLLException: java.lang.RuntimeException: java.lang.NullPointerException

Actions: View

Attachments on bug 872506: 637005 | 637006 | 650163 | 651738