Attachment 692659 Details for Bug 907374 – engine.log

engine.log

engine.log (text/x-log), 11.06 KB, created by Jiri Belka on 2013-02-04 08:53:30 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jiri Belka

Created: 2013-02-04 08:53:30 UTC

Size: 11.06 KB

patch

obsolete

>2013-02-04 09:51:30,531 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-28) Setting new tasks map. The map contains now 0 tasks
>2013-02-04 09:51:30,531 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-28) Cleared all tasks of pool a05c6f22-2a40-4f39-a2a8-aa91b539b217.
>2013-02-04 09:51:30,534 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-28) Could not find vds that is spm and non-operational.
>2013-02-04 09:51:38,194 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8702-12) [12648bd] START, GetImageInfoVDSCommand( storagePoolId = a05c6f22-2a40-4f39-a2a8-aa91b539b217, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = cc4d884d-15d9-4e35-b869-4330245c1b94, imageGroupId = e4e11756-4aab-4d04-95cc-fe5938ee8e92, imageId = 05fd35b2-7ca4-4b33-8ed6-ae0b0083c296), log id: 93b54cb
>2013-02-04 09:51:38,234 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8702-12) [12648bd] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@5daf540, log id: 93b54cb
>2013-02-04 09:51:38,284 INFO  [org.ovirt.engine.core.bll.AddVmTemplateCommand] (ajp-/127.0.0.1:8702-12) [12648bd] Running command: AddVmTemplateCommand internal: false. Entities affected :  ID: a05c6f22-2a40-4f39-a2a8-aa91b539b217 Type: StoragePool
>2013-02-04 09:51:38,287 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-12) [12648bd] START, SetVmStatusVDSCommand( vmId = 75223e86-c39a-4cad-a97c-f5ab8a8ebaac, status = ImageLocked), log id: 2492e31e
>2013-02-04 09:51:38,290 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-12) [12648bd] FINISH, SetVmStatusVDSCommand, log id: 2492e31e
>2013-02-04 09:51:38,319 INFO  [org.ovirt.engine.core.bll.CreateImageTemplateCommand] (ajp-/127.0.0.1:8702-12) [3614576b] Running command: CreateImageTemplateCommand internal: true. Entities affected :  ID: cc4d884d-15d9-4e35-b869-4330245c1b94 Type: Storage
>2013-02-04 09:51:38,325 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8702-12) [3614576b] START, GetImageInfoVDSCommand( storagePoolId = a05c6f22-2a40-4f39-a2a8-aa91b539b217, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = cc4d884d-15d9-4e35-b869-4330245c1b94, imageGroupId = e4e11756-4aab-4d04-95cc-fe5938ee8e92, imageId = 05fd35b2-7ca4-4b33-8ed6-ae0b0083c296), log id: 42eac4f6
>2013-02-04 09:51:38,355 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8702-12) [3614576b] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@5daf540, log id: 42eac4f6
>2013-02-04 09:51:38,368 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-12) [3614576b] START, CopyImageVDSCommand( storagePoolId = a05c6f22-2a40-4f39-a2a8-aa91b539b217, ignoreFailoverLimit = false, compatabilityVersion = 3.2, storageDomainId = cc4d884d-15d9-4e35-b869-4330245c1b94, imageGroupId = e4e11756-4aab-4d04-95cc-fe5938ee8e92, imageId = 05fd35b2-7ca4-4b33-8ed6-ae0b0083c296, dstImageGroupId = 2125d48c-8baf-49e5-9ea4-a1f932577c40, vmId = 75223e86-c39a-4cad-a97c-f5ab8a8ebaac, dstImageId = 70d52e31-d2df-4223-adfa-28af5129c244, imageDescription = Active VM, dstStorageDomainId = cc4d884d-15d9-4e35-b869-4330245c1b94, copyVolumeType = SharedVol, volumeFormat = RAW, preallocate = Sparse, postZero = false, force = false), log id: 2e0e3849
>2013-02-04 09:51:38,368 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-12) [3614576b] -- CopyImageVDSCommand::ExecuteIrsBrokerCommand: calling 'copyImage' with two new parameters: description and UUID
>2013-02-04 09:51:38,369 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-12) [3614576b] -- copyImage parameters:
>                sdUUID=cc4d884d-15d9-4e35-b869-4330245c1b94
>                spUUID=a05c6f22-2a40-4f39-a2a8-aa91b539b217
>                vmGUID=75223e86-c39a-4cad-a97c-f5ab8a8ebaac
>                srcImageGUID=e4e11756-4aab-4d04-95cc-fe5938ee8e92
>                srcVolUUID=05fd35b2-7ca4-4b33-8ed6-ae0b0083c296
>                dstImageGUID=2125d48c-8baf-49e5-9ea4-a1f932577c40
>                dstVolUUID=70d52e31-d2df-4223-adfa-28af5129c244
>                descr=Active VM
>                
>2013-02-04 09:51:38,439 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-12) [3614576b] FINISH, CopyImageVDSCommand, return: 00000000-0000-0000-0000-000000000000, log id: 2e0e3849
>2013-02-04 09:51:38,452 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (ajp-/127.0.0.1:8702-12) [3614576b] EntityAsyncTask::Adding EntityMultiAsyncTasks object for entity 53b9c8c4-d955-49f3-8be8-5d5bd61d2a88
>2013-02-04 09:51:38,452 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (ajp-/127.0.0.1:8702-12) [3614576b] EntityMultiAsyncTasks::AttachTask: Attaching task dee693cb-4488-4a5a-b2b0-a7e85dd26d8e to entity 53b9c8c4-d955-49f3-8be8-5d5bd61d2a88.
>2013-02-04 09:51:38,468 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-12) [3614576b] Adding task dee693cb-4488-4a5a-b2b0-a7e85dd26d8e (Parent Command AddVmTemplate, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2013-02-04 09:51:38,570 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-12) [3614576b] BaseAsyncTask::StartPollingTask: Starting to poll task dee693cb-4488-4a5a-b2b0-a7e85dd26d8e.
>2013-02-04 09:51:38,571 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (ajp-/127.0.0.1:8702-12) [3614576b] EntityMultiAsyncTasks::StartPollingTask: Current Action Type for entity 53b9c8c4-d955-49f3-8be8-5d5bd61d2a88 is AddVmTemplate (determined by task dee693cb-4488-4a5a-b2b0-a7e85dd26d8e)
>2013-02-04 09:51:43,740 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-33) Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
>2013-02-04 09:51:43,761 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-33) SPMAsyncTask::PollTask: Polling task dee693cb-4488-4a5a-b2b0-a7e85dd26d8e (Parent Command AddVmTemplate, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2013-02-04 09:51:43,776 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-33) BaseAsyncTask::OnTaskEndSuccess: Task dee693cb-4488-4a5a-b2b0-a7e85dd26d8e (Parent Command AddVmTemplate, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2013-02-04 09:51:43,777 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-33) EntityAsyncTask::EndActionIfNecessary: All tasks of entity 53b9c8c4-d955-49f3-8be8-5d5bd61d2a88 has ended -> executing EndAction
>2013-02-04 09:51:43,777 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-33) EntityAsyncTask::EndAction: Ending action for 1 tasks (entity ID: 53b9c8c4-d955-49f3-8be8-5d5bd61d2a88): calling EndAction for action type AddVmTemplate.
>2013-02-04 09:51:43,777 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-3-thread-44) EntityAsyncTask::EndCommandAction [within thread] context: Attempting to EndAction AddVmTemplate, executionIndex: 0
>2013-02-04 09:51:43,802 INFO  [org.ovirt.engine.core.bll.AddVmTemplateCommand] (pool-3-thread-44) [12648bd] Ending command successfully: org.ovirt.engine.core.bll.AddVmTemplateCommand
>2013-02-04 09:51:43,804 INFO  [org.ovirt.engine.core.bll.CreateImageTemplateCommand] (pool-3-thread-44) [3614576b] Ending command successfully: org.ovirt.engine.core.bll.CreateImageTemplateCommand
>2013-02-04 09:51:43,805 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-3-thread-44) [3614576b] START, GetImageInfoVDSCommand( storagePoolId = a05c6f22-2a40-4f39-a2a8-aa91b539b217, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = cc4d884d-15d9-4e35-b869-4330245c1b94, imageGroupId = 2125d48c-8baf-49e5-9ea4-a1f932577c40, imageId = 70d52e31-d2df-4223-adfa-28af5129c244), log id: 117861e9
>2013-02-04 09:51:43,837 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-3-thread-44) [3614576b] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@fb3d0c43, log id: 117861e9
>2013-02-04 09:51:43,843 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-3-thread-44) START, SetVmStatusVDSCommand( vmId = 75223e86-c39a-4cad-a97c-f5ab8a8ebaac, status = Down), log id: 6da28d57
>2013-02-04 09:51:43,846 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-3-thread-44) FINISH, SetVmStatusVDSCommand, log id: 6da28d57
>2013-02-04 09:51:43,877 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-3-thread-44) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type AddVmTemplate completed, handling the result.
>2013-02-04 09:51:43,877 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-3-thread-44) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type AddVmTemplate succeeded, clearing tasks.
>2013-02-04 09:51:43,893 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-3-thread-44) SPMAsyncTask::ClearAsyncTask: Attempting to clear task dee693cb-4488-4a5a-b2b0-a7e85dd26d8e
>2013-02-04 09:51:43,893 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-3-thread-44) START, SPMClearTaskVDSCommand( storagePoolId = a05c6f22-2a40-4f39-a2a8-aa91b539b217, ignoreFailoverLimit = false, compatabilityVersion = null, taskId = dee693cb-4488-4a5a-b2b0-a7e85dd26d8e), log id: 39e650f5
>2013-02-04 09:51:43,895 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-3-thread-44) START, HSMClearTaskVDSCommand(HostName = dell-r210ii-04, HostId = 1d600f47-a7f6-45b7-a537-8ccfdaab3ace, taskId=dee693cb-4488-4a5a-b2b0-a7e85dd26d8e), log id: 1b4b5ce0
>2013-02-04 09:51:43,922 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-3-thread-44) FINISH, HSMClearTaskVDSCommand, log id: 1b4b5ce0
>2013-02-04 09:51:43,922 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-3-thread-44) FINISH, SPMClearTaskVDSCommand, log id: 39e650f5
>2013-02-04 09:51:43,946 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-3-thread-44) BaseAsyncTask::RemoveTaskFromDB: Removed task dee693cb-4488-4a5a-b2b0-a7e85dd26d8e from DataBase
>2013-02-04 09:51:43,946 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-3-thread-44) EntityAsyncTask::HandleEndActionResult [within thread]: Removing EntityMultiAsyncTasks object for entity 53b9c8c4-d955-49f3-8be8-5d5bd61d2a88
>2013-02-04 09:51:49,373 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-5) calling GetConfigurationValueQuery (VdcVersion) with null version, using default general for version
>2013-02-04 09:52:05,593 INFO  [org.ovirt.engine.core.bll.AddPermissionCommand] (pool-3-thread-49) [2c7df224] Running command: AddPermissionCommand internal: false. Entities affected :  ID: 53b9c8c4-d955-49f3-8be8-5d5bd61d2a88 Type: VmTemplate

Actions: View

Attachments on bug 907374: 692659