Attachment 629652 Details for Bug 864438 – engine.log

engine.log

engine.log (text/x-log), 45.36 KB, created by Ondra Machacek on 2012-10-18 21:13:48 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Ondra Machacek

Created: 2012-10-18 21:13:48 UTC

Size: 45.36 KB

patch

obsolete

>2012-10-18 22:27:21,286 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (pool-4-thread-46) [67144a3f] START, IsValidVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 7f3cb80d
>2012-10-18 22:27:21,288 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (pool-4-thread-46) [67144a3f] FINISH, IsValidVDSCommand, return: true, log id: 7f3cb80d
>2012-10-18 22:27:21,288 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetIsoListVDSCommand] (pool-4-thread-46) [67144a3f] START, GetIsoListVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 623497c
>2012-10-18 22:27:21,290 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HsmGetIsoListVDSCommand] (pool-4-thread-46) [67144a3f] START, HsmGetIsoListVDSCommand(HostName = RHEL, HostId = d827c16e-170c-11e2-a705-001a4a013f66, storagePoolId=f0257a57-35bf-4f97-841a-902d1c7b121f), log id: 38b88a1f
>2012-10-18 22:27:21,308 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HsmGetIsoListVDSCommand] (pool-4-thread-46) [67144a3f] FINISH, HsmGetIsoListVDSCommand, return: [en_windows_xp_professional_with_service_pack_3_x86_cd_vl_x14-73974.iso], log id: 38b88a1f
>2012-10-18 22:27:21,308 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetIsoListVDSCommand] (pool-4-thread-46) [67144a3f] FINISH, GetIsoListVDSCommand, return: [en_windows_xp_professional_with_service_pack_3_x86_cd_vl_x14-73974.iso], log id: 623497c
>2012-10-18 22:27:21,312 INFO  [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (pool-4-thread-46) [67144a3f] Finished automatic refresh process for ISO file type with success, for storage domain id 56917a11-ad25-4e23-b74f-9f4d81ebd638.
>2012-10-18 22:30:00,001 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-53) Autorecovering hosts is disabled, skipping
>2012-10-18 22:30:00,001 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-53) Autorecovering storage domains is disabled, skipping
>2012-10-18 22:35:00,000 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-8) Autorecovering hosts is disabled, skipping
>2012-10-18 22:35:00,000 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-8) Autorecovering storage domains is disabled, skipping
>2012-10-18 22:36:33,137 INFO  [org.ovirt.engine.core.bll.LoginAdminUserCommand] (ajp-/127.0.0.1:8009-2) Checking if user admin@internal is an admin, result true
>2012-10-18 22:36:33,137 INFO  [org.ovirt.engine.core.bll.LoginAdminUserCommand] (ajp-/127.0.0.1:8009-2) Running command: LoginAdminUserCommand internal: false.
>2012-10-18 22:36:58,139 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-4) START, IsValidVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 5e8507a
>2012-10-18 22:36:58,141 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-4) FINISH, IsValidVDSCommand, return: true, log id: 5e8507a
>2012-10-18 22:36:58,148 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-4) START, IsValidVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 4750b860
>2012-10-18 22:36:58,150 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-4) FINISH, IsValidVDSCommand, return: true, log id: 4750b860
>2012-10-18 22:36:58,194 INFO  [org.ovirt.engine.core.bll.AddVmPoolWithVmsCommand] (pool-4-thread-45) [472ca215] Running command: AddVmPoolWithVmsCommand internal: false. Entities affected :  ID: 99408929-82cf-4dc7-a532-9d998063fa95 Type: VdsGroups,  ID: 6126ec95-ba4f-41cf-95b9-d58104ed85f1 Type: VmTemplate
>2012-10-18 22:36:58,271 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (pool-4-thread-45) [ee5ca81] START, IsValidVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 7153006f
>2012-10-18 22:36:58,273 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (pool-4-thread-45) [ee5ca81] FINISH, IsValidVDSCommand, return: true, log id: 7153006f
>2012-10-18 22:36:58,300 INFO  [org.ovirt.engine.core.bll.AddVmAndAttachToPoolCommand] (pool-4-thread-45) [ee5ca81] Running command: AddVmAndAttachToPoolCommand internal: true. Entities affected :  ID: 99408929-82cf-4dc7-a532-9d998063fa95 Type: VdsGroups,  ID: 6126ec95-ba4f-41cf-95b9-d58104ed85f1 Type: VmTemplate,  ID: 00000000-0000-0000-0000-000000000000 Type: Storage
>2012-10-18 22:36:58,310 INFO  [org.ovirt.engine.core.bll.AddVmCommand] (pool-4-thread-45) [6301a33c] Lock Acquired to object EngineLock [exclusiveLocks= key: aa-1 value: VM_NAME
>, sharedLocks= ]
>2012-10-18 22:36:58,345 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (pool-4-thread-45) [6301a33c] START, IsValidVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 261071a8
>2012-10-18 22:36:58,346 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (pool-4-thread-45) [6301a33c] FINISH, IsValidVDSCommand, return: true, log id: 261071a8
>2012-10-18 22:36:58,348 INFO  [org.ovirt.engine.core.bll.AddVmCommand] (pool-4-thread-45) [6301a33c] Running command: AddVmCommand internal: true. Entities affected :  ID: 99408929-82cf-4dc7-a532-9d998063fa95 Type: VdsGroups,  ID: 6126ec95-ba4f-41cf-95b9-d58104ed85f1 Type: VmTemplate,  ID: 00000000-0000-0000-0000-000000000000 Type: Storage
>2012-10-18 22:36:58,349 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (pool-4-thread-45) [6301a33c] START, IsValidVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 58483f76
>2012-10-18 22:36:58,350 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (pool-4-thread-45) [6301a33c] FINISH, IsValidVDSCommand, return: true, log id: 58483f76
>2012-10-18 22:36:58,357 INFO  [org.ovirt.engine.core.bll.MacPoolManager] (pool-4-thread-45) [6301a33c] MacPoolManager::allocateNewMac entered
>2012-10-18 22:36:58,357 INFO  [org.ovirt.engine.core.bll.MacPoolManager] (pool-4-thread-45) [6301a33c] MacPoolManager::allocateNewMac allocated mac = 00:1a:4a:22:3f:db
>2012-10-18 22:36:58,361 INFO  [org.ovirt.engine.core.bll.MacPoolManager] (pool-4-thread-45) [6301a33c] MacPoolManager::allocateNewMac entered
>2012-10-18 22:36:58,362 INFO  [org.ovirt.engine.core.bll.MacPoolManager] (pool-4-thread-45) [6301a33c] MacPoolManager::allocateNewMac allocated mac = 00:1a:4a:22:3f:de
>2012-10-18 22:36:58,412 INFO  [org.ovirt.engine.core.bll.AddVmCommand] (pool-4-thread-45) [6301a33c] Lock freed to object EngineLock [exclusiveLocks= key: aa-1 value: VM_NAME
>, sharedLocks= ]
>2012-10-18 22:36:58,413 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-4-thread-45) [6301a33c] START, SetVmStatusVDSCommand( vmId = 7ff16cb2-1a4a-4e71-83a7-b2f01647f606, status = ImageLocked), log id: 4ced941a
>2012-10-18 22:36:58,418 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-4-thread-45) [6301a33c] FINISH, SetVmStatusVDSCommand, log id: 4ced941a
>2012-10-18 22:36:58,422 INFO  [org.ovirt.engine.core.bll.CreateSnapshotFromTemplateCommand] (pool-4-thread-45) [17b580a1] Running command: CreateSnapshotFromTemplateCommand internal: true. Entities affected :  ID: c90f72bb-bc65-46c1-b270-545e74221939 Type: Storage
>2012-10-18 22:36:58,430 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-4-thread-45) [17b580a1] START, GetImageInfoVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = c90f72bb-bc65-46c1-b270-545e74221939, imageGroupId = a90cd584-ad60-4669-aa9b-517d5186dddd, imageId = 989ee517-ad40-4e5c-ac53-98d88ad836f3), log id: 223abff7
>2012-10-18 22:36:58,461 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-4-thread-45) [17b580a1] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@1c090b73, log id: 223abff7
>2012-10-18 22:36:58,465 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (pool-4-thread-45) [17b580a1] START, CreateSnapshotVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = 3.1, storageDomainId = f28b8c21-e446-48be-96cc-9a4e0de7e81f, imageGroupId = edd71764-b19a-4ec3-9fa3-4ed250d2b19b, imageSizeInBytes = 11811160064, volumeFormat = COW, newImageId = 9ac4bb0d-5700-4005-ba8d-87998add36df, newImageDescription = , imageId = 989ee517-ad40-4e5c-ac53-98d88ad836f3, sourceImageGroupId = a90cd584-ad60-4669-aa9b-517d5186dddd), log id: d75efdc
>2012-10-18 22:36:58,466 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (pool-4-thread-45) [17b580a1] -- CreateSnapshotVDSCommand::ExecuteIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID
>2012-10-18 22:36:58,466 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (pool-4-thread-45) [17b580a1] -- createVolume parameters:
>                sdUUID=f28b8c21-e446-48be-96cc-9a4e0de7e81f
>                spUUID=f0257a57-35bf-4f97-841a-902d1c7b121f
>                imgGUID=edd71764-b19a-4ec3-9fa3-4ed250d2b19b
>                size=11,811,160,064 bytes
>                volFormat=COW
>                volType=Sparse
>                volUUID=9ac4bb0d-5700-4005-ba8d-87998add36df
>                descr=
>                srcImgGUID=a90cd584-ad60-4669-aa9b-517d5186dddd
>                srcVolUUID=989ee517-ad40-4e5c-ac53-98d88ad836f3
>                
>2012-10-18 22:36:58,533 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (pool-4-thread-45) [17b580a1] FINISH, CreateSnapshotVDSCommand, return: 9ac4bb0d-5700-4005-ba8d-87998add36df, log id: d75efdc
>2012-10-18 22:36:58,537 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-45) [17b580a1] EntityAsyncTask::Adding EntityMultiAsyncTasks object for entity 7ff16cb2-1a4a-4e71-83a7-b2f01647f606
>2012-10-18 22:36:58,537 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (pool-4-thread-45) [17b580a1] EntityMultiAsyncTasks::AttachTask: Attaching task 3a279150-0d85-4dd5-a689-88a85c89b256 to entity 7ff16cb2-1a4a-4e71-83a7-b2f01647f606.
>2012-10-18 22:36:58,540 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskUtils] (pool-4-thread-45) [17b580a1] Adding task 3a279150-0d85-4dd5-a689-88a85c89b256 to DataBase
>2012-10-18 22:36:58,594 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (pool-4-thread-45) [17b580a1] Adding task 3a279150-0d85-4dd5-a689-88a85c89b256 (Parent Command AddVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2012-10-18 22:36:58,717 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-4-thread-45) [17b580a1] BaseAsyncTask::StartPollingTask: Starting to poll task 3a279150-0d85-4dd5-a689-88a85c89b256.
>2012-10-18 22:36:58,717 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (pool-4-thread-45) [17b580a1] EntityMultiAsyncTasks::StartPollingTask: Current Action Type for entity 7ff16cb2-1a4a-4e71-83a7-b2f01647f606 is AddVm (determined by task 3a279150-0d85-4dd5-a689-88a85c89b256)
>2012-10-18 22:36:58,750 INFO  [org.ovirt.engine.core.bll.AddVmToPoolCommand] (pool-4-thread-45) [30f269a9] Running command: AddVmToPoolCommand internal: true. Entities affected :  ID: 8ff21a8a-1963-11e2-9220-001a4a013f66 Type: VmPool
>2012-10-18 22:37:05,707 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-15) [67144a3f] Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
>2012-10-18 22:37:05,722 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-15) [67144a3f] SPMAsyncTask::PollTask: Polling task 3a279150-0d85-4dd5-a689-88a85c89b256 (Parent Command AddVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2012-10-18 22:37:05,727 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-15) [67144a3f] BaseAsyncTask::OnTaskEndSuccess: Task 3a279150-0d85-4dd5-a689-88a85c89b256 (Parent Command AddVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2012-10-18 22:37:05,728 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-15) [67144a3f] EntityAsyncTask::EndActionIfNecessary: All tasks of entity 7ff16cb2-1a4a-4e71-83a7-b2f01647f606 has ended -> executing EndAction
>2012-10-18 22:37:05,728 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-15) [67144a3f] EntityAsyncTask::EndAction: Ending action for 1 tasks (entity ID: 7ff16cb2-1a4a-4e71-83a7-b2f01647f606): calling EndAction for action type AddVm.
>2012-10-18 22:37:05,728 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-45) [67144a3f] EntityAsyncTask::EndCommandAction [within thread]context: Attempting to EndAction AddVm
>2012-10-18 22:37:05,750 INFO  [org.ovirt.engine.core.bll.AddVmCommand] (pool-4-thread-45) [6301a33c] Ending command successfully: org.ovirt.engine.core.bll.AddVmCommand
>2012-10-18 22:37:05,750 INFO  [org.ovirt.engine.core.bll.CreateSnapshotFromTemplateCommand] (pool-4-thread-45) [17b580a1] Ending command successfully: org.ovirt.engine.core.bll.CreateSnapshotFromTemplateCommand
>2012-10-18 22:37:05,752 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-4-thread-45) [17b580a1] START, GetImageInfoVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = f28b8c21-e446-48be-96cc-9a4e0de7e81f, imageGroupId = edd71764-b19a-4ec3-9fa3-4ed250d2b19b, imageId = 9ac4bb0d-5700-4005-ba8d-87998add36df), log id: 30d18cb6
>2012-10-18 22:37:05,786 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-4-thread-45) [17b580a1] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@e4956453, log id: 30d18cb6
>2012-10-18 22:37:05,804 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-4-thread-45) START, SetVmStatusVDSCommand( vmId = 7ff16cb2-1a4a-4e71-83a7-b2f01647f606, status = Down), log id: 49c1a107
>2012-10-18 22:37:05,811 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-4-thread-45) FINISH, SetVmStatusVDSCommand, log id: 49c1a107
>2012-10-18 22:37:05,833 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (pool-4-thread-45) START, UpdateVMVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 00000000-0000-0000-0000-000000000000, infoDictionary.size = 1), log id: 6de741ae
>2012-10-18 22:37:05,857 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (pool-4-thread-45) FINISH, UpdateVMVDSCommand, log id: 6de741ae
>2012-10-18 22:37:05,876 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-45) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type AddVm completed, handling the result.
>2012-10-18 22:37:05,876 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-45) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type AddVm succeeded, clearing tasks.
>2012-10-18 22:37:05,891 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-4-thread-45) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 3a279150-0d85-4dd5-a689-88a85c89b256
>2012-10-18 22:37:05,891 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-4-thread-45) START, SPMClearTaskVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null, taskId = 3a279150-0d85-4dd5-a689-88a85c89b256), log id: 37becac2
>2012-10-18 22:37:05,893 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-4-thread-45) START, HSMClearTaskVDSCommand(HostName = RHEL, HostId = d827c16e-170c-11e2-a705-001a4a013f66, taskId=3a279150-0d85-4dd5-a689-88a85c89b256), log id: 7f89a8ca
>2012-10-18 22:37:05,988 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-4-thread-45) FINISH, HSMClearTaskVDSCommand, log id: 7f89a8ca
>2012-10-18 22:37:05,988 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-4-thread-45) FINISH, SPMClearTaskVDSCommand, log id: 37becac2
>2012-10-18 22:37:05,992 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-4-thread-45) BaseAsyncTask::RemoveTaskFromDB: Removed task 3a279150-0d85-4dd5-a689-88a85c89b256 from DataBase
>2012-10-18 22:37:05,992 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-45) EntityAsyncTask::HandleEndActionResult [within thread]: Removing EntityMultiAsyncTasks object for entity 7ff16cb2-1a4a-4e71-83a7-b2f01647f606
>2012-10-18 22:37:42,101 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8009-8) Checking if user admin@internal is an admin, result true
>2012-10-18 22:37:42,101 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8009-8) Running command: LoginUserCommand internal: false.
>2012-10-18 22:37:42,111 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8009-8) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2012-10-18 22:37:42,112 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8009-8) calling GetConfigurationValueQuery (VdcVersion) with null version, using default general for version
>2012-10-18 22:37:43,277 INFO  [org.ovirt.engine.core.bll.AttachUserToVmFromPoolAndRunCommand] (ajp-/127.0.0.1:8009-7) [c80bc9c] Lock Acquired to object EngineLock [exclusiveLocks= key: fdfc627c-d875-11e0-90f0-83df133b58cc value: org.ovirt.engine.core.bll.AttachUserToVmFromPoolAndRunCommand
>, sharedLocks= ]
>2012-10-18 22:37:43,299 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) [c80bc9c] START, IsValidVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 14680953
>2012-10-18 22:37:43,301 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) [c80bc9c] FINISH, IsValidVDSCommand, return: true, log id: 14680953
>2012-10-18 22:37:43,321 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8009-7) [c80bc9c] START, IsVmDuringInitiatingVDSCommand( vmId = 7ff16cb2-1a4a-4e71-83a7-b2f01647f606), log id: 4ba8eda8
>2012-10-18 22:37:43,321 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8009-7) [c80bc9c] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 4ba8eda8
>2012-10-18 22:37:43,359 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) [c80bc9c] START, IsValidVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 1053f559
>2012-10-18 22:37:43,361 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) [c80bc9c] FINISH, IsValidVDSCommand, return: true, log id: 1053f559
>2012-10-18 22:37:43,388 INFO  [org.ovirt.engine.core.bll.AttachUserToVmFromPoolAndRunCommand] (ajp-/127.0.0.1:8009-7) [c80bc9c] Running command: AttachUserToVmFromPoolAndRunCommand internal: false. Entities affected :  ID: 8ff21a8a-1963-11e2-9220-001a4a013f66 Type: VmPool
>2012-10-18 22:37:43,401 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) [c80bc9c] START, IsValidVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 14d4fe65
>2012-10-18 22:37:43,402 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) [c80bc9c] FINISH, IsValidVDSCommand, return: true, log id: 14d4fe65
>2012-10-18 22:37:43,414 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8009-7) [c80bc9c] START, IsVmDuringInitiatingVDSCommand( vmId = 7ff16cb2-1a4a-4e71-83a7-b2f01647f606), log id: 2c5ec1ad
>2012-10-18 22:37:43,414 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8009-7) [c80bc9c] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 2c5ec1ad
>2012-10-18 22:37:43,427 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) [c80bc9c] START, IsValidVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 2854c4d1
>2012-10-18 22:37:43,428 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) [c80bc9c] FINISH, IsValidVDSCommand, return: true, log id: 2854c4d1
>2012-10-18 22:37:43,446 INFO  [org.ovirt.engine.core.bll.AddPermissionCommand] (ajp-/127.0.0.1:8009-7) [5728f3ce] Running command: AddPermissionCommand internal: true. Entities affected :  ID: 7ff16cb2-1a4a-4e71-83a7-b2f01647f606 Type: VM
>2012-10-18 22:37:43,454 INFO  [org.ovirt.engine.core.bll.AttachUserToVmFromPoolAndRunCommand] (ajp-/127.0.0.1:8009-7) [5728f3ce] Succceeded giving user fdfc627c-d875-11e0-90f0-83df133b58cc permission to Vm 7ff16cb2-1a4a-4e71-83a7-b2f01647f606 
>2012-10-18 22:37:43,480 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp-/127.0.0.1:8009-7) [50e342b] Lock Acquired to object EngineLock [exclusiveLocks= key: 7ff16cb2-1a4a-4e71-83a7-b2f01647f606 value: VM
>, sharedLocks= ]
>2012-10-18 22:37:43,491 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) [50e342b] START, IsValidVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 2f624ae8
>2012-10-18 22:37:43,493 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) [50e342b] FINISH, IsValidVDSCommand, return: true, log id: 2f624ae8
>2012-10-18 22:37:43,518 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8009-7) [50e342b] START, IsVmDuringInitiatingVDSCommand( vmId = 7ff16cb2-1a4a-4e71-83a7-b2f01647f606), log id: 40ad2130
>2012-10-18 22:37:43,518 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8009-7) [50e342b] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 40ad2130
>2012-10-18 22:37:43,569 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp-/127.0.0.1:8009-7) [50e342b] Running command: RunVmCommand internal: true. Entities affected :  ID: 7ff16cb2-1a4a-4e71-83a7-b2f01647f606 Type: VM
>2012-10-18 22:37:43,583 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp-/127.0.0.1:8009-7) [50e342b] VdcBll.RunVmCommand.RunVmAsStateless - Creating snapshot for stateless vm aa-1 - 7ff16cb2-1a4a-4e71-83a7-b2f01647f606
>2012-10-18 22:37:43,616 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) [d993994] START, IsValidVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 59118b9d
>2012-10-18 22:37:43,617 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) [d993994] FINISH, IsValidVDSCommand, return: true, log id: 59118b9d
>2012-10-18 22:37:43,623 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8009-7) [d993994] START, GetImageInfoVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = f28b8c21-e446-48be-96cc-9a4e0de7e81f, imageGroupId = edd71764-b19a-4ec3-9fa3-4ed250d2b19b, imageId = 9ac4bb0d-5700-4005-ba8d-87998add36df), log id: 7bf68ddc
>2012-10-18 22:37:43,658 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8009-7) [d993994] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@e4956453, log id: 7bf68ddc
>2012-10-18 22:37:43,667 INFO  [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (ajp-/127.0.0.1:8009-7) [d993994] Running command: CreateAllSnapshotsFromVmCommand internal: true. Entities affected :  ID: 7ff16cb2-1a4a-4e71-83a7-b2f01647f606 Type: VM
>2012-10-18 22:37:43,697 INFO  [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (ajp-/127.0.0.1:8009-7) [d993994] Lock freed to object EngineLock [exclusiveLocks= key: 7ff16cb2-1a4a-4e71-83a7-b2f01647f606 value: VM
>, sharedLocks= ]
>2012-10-18 22:37:43,712 INFO  [org.ovirt.engine.core.bll.CreateSnapshotCommand] (ajp-/127.0.0.1:8009-7) [6a3722b6] Running command: CreateSnapshotCommand internal: true. Entities affected :  ID: 00000000-0000-0000-0000-000000000000 Type: Storage
>2012-10-18 22:37:43,720 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8009-7) [6a3722b6] START, GetImageInfoVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = f28b8c21-e446-48be-96cc-9a4e0de7e81f, imageGroupId = edd71764-b19a-4ec3-9fa3-4ed250d2b19b, imageId = 9ac4bb0d-5700-4005-ba8d-87998add36df), log id: 73c0e0d1
>2012-10-18 22:37:43,754 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8009-7) [6a3722b6] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@e4956453, log id: 73c0e0d1
>2012-10-18 22:37:43,756 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (ajp-/127.0.0.1:8009-7) [6a3722b6] START, CreateSnapshotVDSCommand( storagePoolId = f0257a57-35bf-4f97-841a-902d1c7b121f, ignoreFailoverLimit = false, compatabilityVersion = 3.1, storageDomainId = f28b8c21-e446-48be-96cc-9a4e0de7e81f, imageGroupId = edd71764-b19a-4ec3-9fa3-4ed250d2b19b, imageSizeInBytes = 11811160064, volumeFormat = COW, newImageId = 2d1d8bd5-6c1f-470a-a621-02ae623a5dae, newImageDescription = , imageId = 9ac4bb0d-5700-4005-ba8d-87998add36df, sourceImageGroupId = edd71764-b19a-4ec3-9fa3-4ed250d2b19b), log id: 1fc5b7a8
>2012-10-18 22:37:43,756 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (ajp-/127.0.0.1:8009-7) [6a3722b6] -- CreateSnapshotVDSCommand::ExecuteIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID
>2012-10-18 22:37:43,757 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (ajp-/127.0.0.1:8009-7) [6a3722b6] -- createVolume parameters:
>                sdUUID=f28b8c21-e446-48be-96cc-9a4e0de7e81f
>                spUUID=f0257a57-35bf-4f97-841a-902d1c7b121f
>                imgGUID=edd71764-b19a-4ec3-9fa3-4ed250d2b19b
>                size=11,811,160,064 bytes
>                volFormat=COW
>                volType=Sparse
>                volUUID=2d1d8bd5-6c1f-470a-a621-02ae623a5dae
>                descr=
>                srcImgGUID=edd71764-b19a-4ec3-9fa3-4ed250d2b19b
>                srcVolUUID=9ac4bb0d-5700-4005-ba8d-87998add36df
>                
>2012-10-18 22:37:43,819 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateSnapshotVDSCommand] (ajp-/127.0.0.1:8009-7) [6a3722b6] FINISH, CreateSnapshotVDSCommand, return: 2d1d8bd5-6c1f-470a-a621-02ae623a5dae, log id: 1fc5b7a8
>2012-10-18 22:37:43,828 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (ajp-/127.0.0.1:8009-7) [6a3722b6] EntityAsyncTask::Adding EntityMultiAsyncTasks object for entity 7ff16cb2-1a4a-4e71-83a7-b2f01647f606
>2012-10-18 22:37:43,828 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (ajp-/127.0.0.1:8009-7) [6a3722b6] EntityMultiAsyncTasks::AttachTask: Attaching task 4b4de9b0-88d6-49f3-8bdd-16fe2f95bbc1 to entity 7ff16cb2-1a4a-4e71-83a7-b2f01647f606.
>2012-10-18 22:37:43,830 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskUtils] (ajp-/127.0.0.1:8009-7) [6a3722b6] Adding task 4b4de9b0-88d6-49f3-8bdd-16fe2f95bbc1 to DataBase
>2012-10-18 22:37:43,858 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8009-7) [6a3722b6] Adding task 4b4de9b0-88d6-49f3-8bdd-16fe2f95bbc1 (Parent Command RunVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2012-10-18 22:37:43,888 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8009-7) [6a3722b6] BaseAsyncTask::StartPollingTask: Starting to poll task 4b4de9b0-88d6-49f3-8bdd-16fe2f95bbc1.
>2012-10-18 22:37:43,888 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (ajp-/127.0.0.1:8009-7) [6a3722b6] EntityMultiAsyncTasks::StartPollingTask: Current Action Type for entity 7ff16cb2-1a4a-4e71-83a7-b2f01647f606 is RunVm (determined by task 4b4de9b0-88d6-49f3-8bdd-16fe2f95bbc1)
>2012-10-18 22:37:43,892 INFO  [org.ovirt.engine.core.bll.AttachUserToVmFromPoolAndRunCommand] (ajp-/127.0.0.1:8009-7) [6a3722b6] Lock freed to object EngineLock [exclusiveLocks= key: fdfc627c-d875-11e0-90f0-83df133b58cc value: org.ovirt.engine.core.bll.AttachUserToVmFromPoolAndRunCommand
>, sharedLocks= ]
>2012-10-18 22:37:45,729 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-52) Polling and updating Async Tasks: 2 tasks, 1 tasks to poll now
>2012-10-18 22:37:45,745 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-52) SPMAsyncTask::PollTask: Polling task 4b4de9b0-88d6-49f3-8bdd-16fe2f95bbc1 (Parent Command RunVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2012-10-18 22:37:45,750 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-52) BaseAsyncTask::OnTaskEndSuccess: Task 4b4de9b0-88d6-49f3-8bdd-16fe2f95bbc1 (Parent Command RunVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2012-10-18 22:37:45,751 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-52) EntityAsyncTask::EndActionIfNecessary: All tasks of entity 7ff16cb2-1a4a-4e71-83a7-b2f01647f606 has ended -> executing EndAction
>2012-10-18 22:37:45,751 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-52) EntityAsyncTask::EndAction: Ending action for 1 tasks (entity ID: 7ff16cb2-1a4a-4e71-83a7-b2f01647f606): calling EndAction for action type RunVm.
>2012-10-18 22:37:45,752 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-45) EntityAsyncTask::EndCommandAction [within thread]context: Attempting to EndAction RunVm
>2012-10-18 22:37:45,756 ERROR [org.ovirt.engine.core.bll.CommandsFactory] (pool-4-thread-45) Cant find constructor for type org.ovirt.engine.core.bll.RunVmCommand with parameter types: [class org.ovirt.engine.core.common.action.CreateAllSnapshotsFromVmParameters]
>2012-10-18 22:37:45,756 ERROR [org.ovirt.engine.core.bll.CommandsFactory] (pool-4-thread-45) CommandsFactory [parameter: VdcActionParametersBase]: Failed to get type information using reflection for Action: RunVm: java.lang.RuntimeException: could not find matching constructor for Command class class org.ovirt.engine.core.bll.RunVmCommand
>	at org.ovirt.engine.core.bll.CommandsFactory.findCommandConstructor(CommandsFactory.java:146) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.CommandsFactory.CreateCommand(CommandsFactory.java:36) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.Backend.endAction(Backend.java:354) [engine-bll.jar:]
>	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_05-icedtea]
>	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_05-icedtea]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_05-icedtea]
>	at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_05-icedtea]
>	at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.ovirt.engine.core.utils.ThreadLocalSessionCleanerInterceptor.injectWebContextToThreadLocal(ThreadLocalSessionCleanerInterceptor.java:11) [engine-utils.jar:]
>	at sun.reflect.GeneratedMethodAccessor7.invoke(Unknown Source) [:1.7.0_05-icedtea]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_05-icedtea]
>	at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_05-icedtea]
>	at org.jboss.as.ee.component.ManagedReferenceLifecycleMethodInterceptorFactory$ManagedReferenceLifecycleMethodInterceptor.processInvocation(ManagedReferenceLifecycleMethodInterceptorFactory.java:123) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ejb3.component.invocationmetrics.ExecutionTimeInterceptor.processInvocation(ExecutionTimeInterceptor.java:43) [jboss-as-ejb3.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [jboss-as-ejb3.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:210) [jboss-as-ejb3.jar:7.1.2.Final-redhat-1]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:362) [jboss-as-ejb3.jar:7.1.2.Final-redhat-1]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:193) [jboss-as-ejb3.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:42) [jboss-as-ejb3.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:176) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:72) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.ovirt.engine.core.bll.interfaces.BackendInternal$$$view9.endAction(Unknown Source) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.EntityAsyncTask.EndCommandAction(EntityAsyncTask.java:134) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.EntityAsyncTask.access$000(EntityAsyncTask.java:23) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.EntityAsyncTask$1.run(EntityAsyncTask.java:104) [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_05-icedtea]
>	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) [rt.jar:1.7.0_05-icedtea]
>	at java.util.concurrent.FutureTask.run(FutureTask.java:166) [rt.jar:1.7.0_05-icedtea]
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) [rt.jar:1.7.0_05-icedtea]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) [rt.jar:1.7.0_05-icedtea]
>	at java.lang.Thread.run(Thread.java:722) [rt.jar:1.7.0_05-icedtea]
>
>2012-10-18 22:37:45,762 ERROR [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-45) EntityAsyncTask::EndCommandAction [within thread]: EndAction for action type RunVm threw an exception: java.lang.NullPointerException
>	at org.ovirt.engine.core.bll.Backend.endAction(Backend.java:355) [engine-bll.jar:]
>	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_05-icedtea]
>	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_05-icedtea]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_05-icedtea]
>	at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_05-icedtea]
>	at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.ovirt.engine.core.utils.ThreadLocalSessionCleanerInterceptor.injectWebContextToThreadLocal(ThreadLocalSessionCleanerInterceptor.java:11) [engine-utils.jar:]
>	at sun.reflect.GeneratedMethodAccessor7.invoke(Unknown Source) [:1.7.0_05-icedtea]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_05-icedtea]
>	at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_05-icedtea]
>	at org.jboss.as.ee.component.ManagedReferenceLifecycleMethodInterceptorFactory$ManagedReferenceLifecycleMethodInterceptor.processInvocation(ManagedReferenceLifecycleMethodInterceptorFactory.java:123) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ejb3.component.invocationmetrics.ExecutionTimeInterceptor.processInvocation(ExecutionTimeInterceptor.java:43) [jboss-as-ejb3.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [jboss-as-ejb3.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:210) [jboss-as-ejb3.jar:7.1.2.Final-redhat-1]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:362) [jboss-as-ejb3.jar:7.1.2.Final-redhat-1]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:193) [jboss-as-ejb3.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:42) [jboss-as-ejb3.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:176) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.jar:1.1.1.Final-redhat-1]
>	at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:72) [jboss-as-ee.jar:7.1.2.Final-redhat-1]
>	at org.ovirt.engine.core.bll.interfaces.BackendInternal$$$view9.endAction(Unknown Source) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.EntityAsyncTask.EndCommandAction(EntityAsyncTask.java:134) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.EntityAsyncTask.access$000(EntityAsyncTask.java:23) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.EntityAsyncTask$1.run(EntityAsyncTask.java:104) [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_05-icedtea]
>	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) [rt.jar:1.7.0_05-icedtea]
>	at java.util.concurrent.FutureTask.run(FutureTask.java:166) [rt.jar:1.7.0_05-icedtea]
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) [rt.jar:1.7.0_05-icedtea]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) [rt.jar:1.7.0_05-icedtea]
>	at java.lang.Thread.run(Thread.java:722) [rt.jar:1.7.0_05-icedtea]

Actions: View

Attachments on bug 864438: 629652