Attachment 662907 Details for Bug 886855 – engine.log

engine.log

engine4.log (text/x-log), 25.25 KB, created by Ondra Machacek on 2012-12-13 11:34:07 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Ondra Machacek

Created: 2012-12-13 11:34:07 UTC

Size: 25.25 KB

patch

obsolete

>2012-12-13 12:30:00,001 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-79) Checking autorecoverable hosts
>2012-12-13 12:30:00,003 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-79) Autorecovering 0 hosts
>2012-12-13 12:30:00,003 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-79) Checking autorecoverable hosts done
>2012-12-13 12:30:00,003 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-79) Autorecovering storage domains is disabled, skipping
>2012-12-13 12:30:29,050 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp-/127.0.0.1:8702-2) [55d150c] START, ValidateStorageServerConnectionVDSCommand(HostName = user_api_tests__host, HostId = 3ac6eace-4517-11e2-93aa-001a4a013f66, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.34.63.199:/om02, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 4a6af5e9
>2012-12-13 12:30:29,066 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp-/127.0.0.1:8702-2) [55d150c] FINISH, ValidateStorageServerConnectionVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 4a6af5e9
>2012-12-13 12:30:29,073 INFO  [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (ajp-/127.0.0.1:8702-2) [55d150c] Running command: AddStorageServerConnectionCommand internal: false. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2012-12-13 12:30:29,078 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp-/127.0.0.1:8702-2) [55d150c] START, ConnectStorageServerVDSCommand(HostName = user_api_tests__host, HostId = 3ac6eace-4517-11e2-93aa-001a4a013f66, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.34.63.199:/om02, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 46c43661
>2012-12-13 12:30:29,127 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp-/127.0.0.1:8702-2) [55d150c] FINISH, ConnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 46c43661
>2012-12-13 12:30:29,206 INFO  [org.ovirt.engine.core.bll.storage.AddNFSStorageDomainCommand] (ajp-/127.0.0.1:8702-4) [355a5049] Running command: AddNFSStorageDomainCommand internal: false. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2012-12-13 12:30:29,219 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (ajp-/127.0.0.1:8702-4) [355a5049] START, CreateStorageDomainVDSCommand(HostName = user_api_tests__host, HostId = 3ac6eace-4517-11e2-93aa-001a4a013f66, storageDomain=org.ovirt.engine.core.common.businessentities.storage_domain_static@abff30c4, args=10.34.63.199:/om02), log id: 42924ba0
>2012-12-13 12:30:31,596 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (ajp-/127.0.0.1:8702-4) [355a5049] FINISH, CreateStorageDomainVDSCommand, log id: 42924ba0
>2012-12-13 12:30:31,599 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (ajp-/127.0.0.1:8702-4) [355a5049] START, GetStorageDomainStatsVDSCommand(HostName = user_api_tests__host, HostId = 3ac6eace-4517-11e2-93aa-001a4a013f66, storageDomainId=95db1074-760e-4bf4-b23e-3139be66592b), log id: 271cccb6
>2012-12-13 12:30:31,617 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (ajp-/127.0.0.1:8702-4) [355a5049] FINISH, GetStorageDomainStatsVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage_domains@514cf310, log id: 271cccb6
>2012-12-13 12:30:31,711 INFO  [org.ovirt.engine.core.bll.storage.RemoveStorageServerConnectionCommand] (ajp-/127.0.0.1:8702-1) [4e35a1f8] Running command: RemoveStorageServerConnectionCommand internal: false. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2012-12-13 12:30:31,714 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (ajp-/127.0.0.1:8702-1) [4e35a1f8] START, DisconnectStorageServerVDSCommand(HostName = user_api_tests__host, HostId = 3ac6eace-4517-11e2-93aa-001a4a013f66, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.34.63.199:/om02, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: d317a38
>2012-12-13 12:30:33,839 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (ajp-/127.0.0.1:8702-1) [4e35a1f8] FINISH, DisconnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: d317a38
>2012-12-13 12:30:33,942 INFO  [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] (ajp-/127.0.0.1:8702-6) [7d15936c] Running command: AttachStorageDomainToPoolCommand internal: false. Entities affected :  ID: 95db1074-760e-4bf4-b23e-3139be66592b Type: Storage
>2012-12-13 12:30:33,977 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (pool-4-thread-50) [2a2439a] START, ValidateStorageServerConnectionVDSCommand(HostName = user_api_tests__host, HostId = 3ac6eace-4517-11e2-93aa-001a4a013f66, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: eb63c1a0-b994-47ba-ad74-3ad6afa54839, connection: 10.34.63.199:/om02, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 32ba2858
>2012-12-13 12:30:33,995 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (pool-4-thread-50) [2a2439a] FINISH, ValidateStorageServerConnectionVDSCommand, return: {eb63c1a0-b994-47ba-ad74-3ad6afa54839=0}, log id: 32ba2858
>2012-12-13 12:30:33,996 INFO  [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (pool-4-thread-50) [2a2439a] Running command: ConnectStorageToVdsCommand internal: true. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2012-12-13 12:30:33,999 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-4-thread-50) [2a2439a] START, ConnectStorageServerVDSCommand(HostName = user_api_tests__host, HostId = 3ac6eace-4517-11e2-93aa-001a4a013f66, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: eb63c1a0-b994-47ba-ad74-3ad6afa54839, connection: 10.34.63.199:/om02, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 6b8dc805
>2012-12-13 12:30:34,042 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-4-thread-50) [2a2439a] FINISH, ConnectStorageServerVDSCommand, return: {eb63c1a0-b994-47ba-ad74-3ad6afa54839=0}, log id: 6b8dc805
>2012-12-13 12:30:34,045 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (ajp-/127.0.0.1:8702-6) [7d15936c] START, AttachStorageDomainVDSCommand( storagePoolId = b8a247a6-4515-11e2-bdd7-001a4a013f66, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 95db1074-760e-4bf4-b23e-3139be66592b), log id: 3db9ffbe
>2012-12-13 12:30:58,930 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (ajp-/127.0.0.1:8702-6) [7d15936c] FINISH, AttachStorageDomainVDSCommand, log id: 3db9ffbe
>2012-12-13 12:31:09,204 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp-/127.0.0.1:8702-8) Lock Acquired to object EngineLock [exclusiveLocks= key: 95db1074-760e-4bf4-b23e-3139be66592b value: STORAGE
>, sharedLocks= ]
>2012-12-13 12:31:09,242 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-4-thread-50) [72be13f8] Running command: ActivateStorageDomainCommand internal: false. Entities affected :  ID: 95db1074-760e-4bf4-b23e-3139be66592b Type: Storage
>2012-12-13 12:31:09,259 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-4-thread-50) [72be13f8] Lock freed to object EngineLock [exclusiveLocks= key: 95db1074-760e-4bf4-b23e-3139be66592b value: STORAGE
>, sharedLocks= ]
>2012-12-13 12:31:09,260 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-4-thread-50) [72be13f8] ActivateStorage Domain. Before Connect all hosts to pool. Time:12/13/12 12:31 PM
>2012-12-13 12:31:09,310 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (pool-4-thread-48) [43f841d] START, ValidateStorageServerConnectionVDSCommand(HostName = user_api_tests__host, HostId = 3ac6eace-4517-11e2-93aa-001a4a013f66, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: eb63c1a0-b994-47ba-ad74-3ad6afa54839, connection: 10.34.63.199:/om02, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 2546b9c6
>2012-12-13 12:31:09,331 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (pool-4-thread-48) [43f841d] FINISH, ValidateStorageServerConnectionVDSCommand, return: {eb63c1a0-b994-47ba-ad74-3ad6afa54839=0}, log id: 2546b9c6
>2012-12-13 12:31:09,331 INFO  [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (pool-4-thread-48) [43f841d] Running command: ConnectStorageToVdsCommand internal: true. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2012-12-13 12:31:09,334 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-4-thread-48) [43f841d] START, ConnectStorageServerVDSCommand(HostName = user_api_tests__host, HostId = 3ac6eace-4517-11e2-93aa-001a4a013f66, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: eb63c1a0-b994-47ba-ad74-3ad6afa54839, connection: 10.34.63.199:/om02, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 75578221
>2012-12-13 12:31:09,350 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-4-thread-48) [43f841d] FINISH, ConnectStorageServerVDSCommand, return: {eb63c1a0-b994-47ba-ad74-3ad6afa54839=0}, log id: 75578221
>2012-12-13 12:31:09,355 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (pool-4-thread-50) [72be13f8] START, ActivateStorageDomainVDSCommand( storagePoolId = b8a247a6-4515-11e2-bdd7-001a4a013f66, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 95db1074-760e-4bf4-b23e-3139be66592b), log id: 622e4c4f
>2012-12-13 12:31:12,684 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (pool-4-thread-50) [72be13f8] FINISH, ActivateStorageDomainVDSCommand, log id: 622e4c4f
>2012-12-13 12:31:12,684 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-4-thread-50) [72be13f8] ActivateStorage Domain. After Connect all hosts to pool. Time:12/13/12 12:31 PM
>2012-12-13 12:31:12,713 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.RefreshStoragePoolVDSCommand] (pool-4-thread-48) [72be13f8] START, RefreshStoragePoolVDSCommand(HostName = user_api_tests__host, HostId = 3ac6eace-4517-11e2-93aa-001a4a013f66, storagePoolId = b8a247a6-4515-11e2-bdd7-001a4a013f66, masterStorageDomainId=19e514e1-4142-4af9-b0af-e5ab0f513f03, msterVersion=1), log id: 32cfdf6c
>2012-12-13 12:31:15,072 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.RefreshStoragePoolVDSCommand] (pool-4-thread-48) [72be13f8] FINISH, RefreshStoragePoolVDSCommand, log id: 32cfdf6c
>2012-12-13 12:31:15,073 INFO  [org.ovirt.engine.core.bll.storage.RefreshPoolSingleAsyncOperation] (pool-4-thread-48) [72be13f8] Refreshed vds user_api_tests__host in pool user_api_tests__dc
>2012-12-13 12:31:15,079 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-4-thread-50) [72be13f8] ActivateStorage Domain. After change storage pool status in vds. Time:12/13/12 12:31 PM
>2012-12-13 12:31:28,124 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (pool-4-thread-50) [52e8dc2] Running command: AddDiskCommand internal: false. Entities affected :  ID: 95db1074-760e-4bf4-b23e-3139be66592b Type: Storage
>2012-12-13 12:31:28,140 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (pool-4-thread-50) [26764a9d] Running command: AddImageFromScratchCommand internal: true. Entities affected :  ID: 95db1074-760e-4bf4-b23e-3139be66592b Type: Storage
>2012-12-13 12:31:28,214 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (pool-4-thread-50) [26764a9d] START, CreateImageVDSCommand( storagePoolId = b8a247a6-4515-11e2-bdd7-001a4a013f66, ignoreFailoverLimit = false, compatabilityVersion = 3.1, storageDomainId = 95db1074-760e-4bf4-b23e-3139be66592b, imageGroupId = 3b4f9e0f-460b-46bb-bab8-83a14d22f3cb, imageSizeInBytes = 10737418240, volumeFormat = RAW, newImageId = 24d0c6c1-fc9c-4809-9d0f-96dead61f94c, newImageDescription = ), log id: 5ea3a8a0
>2012-12-13 12:31:28,214 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (pool-4-thread-50) [26764a9d] -- CreateImageVDSCommand::ExecuteIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID
>2012-12-13 12:31:28,216 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (pool-4-thread-50) [26764a9d] -- createVolume parameters:
>                sdUUID=95db1074-760e-4bf4-b23e-3139be66592b
>                spUUID=b8a247a6-4515-11e2-bdd7-001a4a013f66
>                imgGUID=3b4f9e0f-460b-46bb-bab8-83a14d22f3cb
>                size=10,737,418,240 bytes
>                volFormat=RAW
>                volType=Sparse
>                volUUID=24d0c6c1-fc9c-4809-9d0f-96dead61f94c
>                descr=
>                srcImgGUID=00000000-0000-0000-0000-000000000000
>                srcVolUUID=00000000-0000-0000-0000-000000000000
>                
>2012-12-13 12:31:28,285 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (pool-4-thread-50) [26764a9d] FINISH, CreateImageVDSCommand, return: 24d0c6c1-fc9c-4809-9d0f-96dead61f94c, log id: 5ea3a8a0
>2012-12-13 12:31:28,295 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-50) [26764a9d] EntityAsyncTask::Adding EntityMultiAsyncTasks object for entity 3b4f9e0f-460b-46bb-bab8-83a14d22f3cb
>2012-12-13 12:31:28,296 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (pool-4-thread-50) [26764a9d] EntityMultiAsyncTasks::AttachTask: Attaching task b89914d8-b421-453b-b081-4132b81b9fa1 to entity 3b4f9e0f-460b-46bb-bab8-83a14d22f3cb.
>2012-12-13 12:31:28,306 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskUtils] (pool-4-thread-50) [26764a9d] Adding task b89914d8-b421-453b-b081-4132b81b9fa1 to DataBase
>2012-12-13 12:31:28,391 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (pool-4-thread-50) [26764a9d] Adding task b89914d8-b421-453b-b081-4132b81b9fa1 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2012-12-13 12:31:28,427 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-4-thread-50) [26764a9d] BaseAsyncTask::StartPollingTask: Starting to poll task b89914d8-b421-453b-b081-4132b81b9fa1.
>2012-12-13 12:31:28,428 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (pool-4-thread-50) [26764a9d] EntityMultiAsyncTasks::StartPollingTask: Current Action Type for entity 3b4f9e0f-460b-46bb-bab8-83a14d22f3cb is AddDisk (determined by task b89914d8-b421-453b-b081-4132b81b9fa1)
>2012-12-13 12:31:35,675 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-50) Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
>2012-12-13 12:31:35,694 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-50) SPMAsyncTask::PollTask: Polling task b89914d8-b421-453b-b081-4132b81b9fa1 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2012-12-13 12:31:35,703 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-50) BaseAsyncTask::OnTaskEndSuccess: Task b89914d8-b421-453b-b081-4132b81b9fa1 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2012-12-13 12:31:35,704 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-50) EntityAsyncTask::EndActionIfNecessary: All tasks of entity 3b4f9e0f-460b-46bb-bab8-83a14d22f3cb has ended -> executing EndAction
>2012-12-13 12:31:35,704 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-50) EntityAsyncTask::EndAction: Ending action for 1 tasks (entity ID: 3b4f9e0f-460b-46bb-bab8-83a14d22f3cb): calling EndAction for action type AddDisk.
>2012-12-13 12:31:35,706 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-50) EntityAsyncTask::EndCommandAction [within thread] context: Attempting to EndAction AddDisk, executionIndex: 0
>2012-12-13 12:31:35,729 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (pool-4-thread-50) Ending command successfully: org.ovirt.engine.core.bll.AddDiskCommand
>2012-12-13 12:31:35,732 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (pool-4-thread-50) [26764a9d] Ending command successfully: org.ovirt.engine.core.bll.AddImageFromScratchCommand
>2012-12-13 12:31:35,747 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-4-thread-50) [26764a9d] START, GetImageInfoVDSCommand( storagePoolId = b8a247a6-4515-11e2-bdd7-001a4a013f66, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 95db1074-760e-4bf4-b23e-3139be66592b, imageGroupId = 3b4f9e0f-460b-46bb-bab8-83a14d22f3cb, imageId = 24d0c6c1-fc9c-4809-9d0f-96dead61f94c), log id: 2e66e7b6
>2012-12-13 12:31:35,780 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-4-thread-50) [26764a9d] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@63c7723e, log id: 2e66e7b6
>2012-12-13 12:31:35,796 WARN  [org.ovirt.engine.core.bll.AddDiskCommand] (pool-4-thread-50) VmCommand::EndVmCommand: Vm is null - not performing EndAction on Vm
>2012-12-13 12:31:35,800 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-50) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type AddDisk completed, handling the result.
>2012-12-13 12:31:35,800 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-50) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type AddDisk succeeded, clearing tasks.
>2012-12-13 12:31:35,804 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-4-thread-50) SPMAsyncTask::ClearAsyncTask: Attempting to clear task b89914d8-b421-453b-b081-4132b81b9fa1
>2012-12-13 12:31:35,806 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-4-thread-50) START, SPMClearTaskVDSCommand( storagePoolId = b8a247a6-4515-11e2-bdd7-001a4a013f66, ignoreFailoverLimit = false, compatabilityVersion = null, taskId = b89914d8-b421-453b-b081-4132b81b9fa1), log id: 7433bf42
>2012-12-13 12:31:35,808 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-4-thread-50) START, HSMClearTaskVDSCommand(HostName = user_api_tests__host, HostId = 3ac6eace-4517-11e2-93aa-001a4a013f66, taskId=b89914d8-b421-453b-b081-4132b81b9fa1), log id: 606a47b5
>2012-12-13 12:31:35,832 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-4-thread-50) FINISH, HSMClearTaskVDSCommand, log id: 606a47b5
>2012-12-13 12:31:35,832 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-4-thread-50) FINISH, SPMClearTaskVDSCommand, log id: 7433bf42
>2012-12-13 12:31:35,839 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-4-thread-50) BaseAsyncTask::RemoveTaskFromDB: Removed task b89914d8-b421-453b-b081-4132b81b9fa1 from DataBase
>2012-12-13 12:31:35,839 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-50) EntityAsyncTask::HandleEndActionResult [within thread]: Removing EntityMultiAsyncTasks object for entity 3b4f9e0f-460b-46bb-bab8-83a14d22f3cb
>2012-12-13 12:31:56,132 INFO  [org.ovirt.engine.core.bll.AddPermissionCommand] (pool-4-thread-50) [6e9883e9] Running command: AddPermissionCommand internal: false. Entities affected :  ID: 3b4f9e0f-460b-46bb-bab8-83a14d22f3cb Type: Disk
>2012-12-13 12:32:31,814 INFO  [org.ovirt.engine.core.bll.storage.DeactivateStorageDomainCommand] (ajp-/127.0.0.1:8702-8) Lock Acquired to object EngineLock [exclusiveLocks= key: 95db1074-760e-4bf4-b23e-3139be66592b value: STORAGE
>, sharedLocks= key: b8a247a6-4515-11e2-bdd7-001a4a013f66 value: POOL
>]
>2012-12-13 12:32:31,881 INFO  [org.ovirt.engine.core.bll.storage.DeactivateStorageDomainCommand] (pool-4-thread-48) [6cd20cf1] Running command: DeactivateStorageDomainCommand internal: false. Entities affected :  ID: 95db1074-760e-4bf4-b23e-3139be66592b Type: Storage
>2012-12-13 12:32:31,902 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DeactivateStorageDomainVDSCommand] (pool-4-thread-48) [6cd20cf1] START, DeactivateStorageDomainVDSCommand( storagePoolId = b8a247a6-4515-11e2-bdd7-001a4a013f66, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 95db1074-760e-4bf4-b23e-3139be66592b, masterDomainId = 00000000-0000-0000-0000-000000000000, masterVersion = 1), log id: 67f11b04
>2012-12-13 12:32:33,938 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DeactivateStorageDomainVDSCommand] (pool-4-thread-48) [6cd20cf1] FINISH, DeactivateStorageDomainVDSCommand, log id: 67f11b04
>2012-12-13 12:32:33,938 INFO  [org.ovirt.engine.core.bll.storage.DeactivateStorageDomainCommand] (pool-4-thread-48) [6cd20cf1] Lock freed to object EngineLock [exclusiveLocks= key: 95db1074-760e-4bf4-b23e-3139be66592b value: STORAGE
>, sharedLocks= key: b8a247a6-4515-11e2-bdd7-001a4a013f66 value: POOL
>]
>2012-12-13 12:32:33,957 INFO  [org.ovirt.engine.core.bll.storage.AfterDeactivateSingleAsyncOperation] (pool-4-thread-50) [6cd20cf1] After deactivate treatment vds: user_api_tests__host,pool user_api_tests__dc
>2012-12-13 12:32:33,971 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.RefreshStoragePoolVDSCommand] (pool-4-thread-50) [6cd20cf1] START, RefreshStoragePoolVDSCommand(HostName = user_api_tests__host, HostId = 3ac6eace-4517-11e2-93aa-001a4a013f66, storagePoolId = b8a247a6-4515-11e2-bdd7-001a4a013f66, masterStorageDomainId=19e514e1-4142-4af9-b0af-e5ab0f513f03, msterVersion=1), log id: 3cb35418
>2012-12-13 12:32:36,388 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.RefreshStoragePoolVDSCommand] (pool-4-thread-50) [6cd20cf1] FINISH, RefreshStoragePoolVDSCommand, log id: 3cb35418
>2012-12-13 12:32:36,392 INFO  [org.ovirt.engine.core.bll.storage.RemoveStorageServerConnectionCommand] (pool-4-thread-48) [6c37551f] Running command: RemoveStorageServerConnectionCommand internal: true. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2012-12-13 12:32:36,394 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (pool-4-thread-48) [6c37551f] START, DisconnectStorageServerVDSCommand(HostName = user_api_tests__host, HostId = 3ac6eace-4517-11e2-93aa-001a4a013f66, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: eb63c1a0-b994-47ba-ad74-3ad6afa54839, connection: 10.34.63.199:/om02, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 29165c64
>2012-12-13 12:32:38,515 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (pool-4-thread-48) [6c37551f] FINISH, DisconnectStorageServerVDSCommand, return: {eb63c1a0-b994-47ba-ad74-3ad6afa54839=0}, log id: 29165c64
>2012-12-13 12:32:46,896 INFO  [org.ovirt.engine.core.bll.storage.ForceRemoveStorageDomainCommand] (ajp-/127.0.0.1:8702-7) Lock Acquired to object EngineLock [exclusiveLocks= key: 95db1074-760e-4bf4-b23e-3139be66592b value: STORAGE
>, sharedLocks= ]
>2012-12-13 12:32:46,931 INFO  [org.ovirt.engine.core.bll.storage.ForceRemoveStorageDomainCommand] (pool-4-thread-50) [6eaa1a17] Running command: ForceRemoveStorageDomainCommand internal: false. Entities affected :  ID: 95db1074-760e-4bf4-b23e-3139be66592b Type: Storage
>2012-12-13 12:32:46,933 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DetachStorageDomainVDSCommand] (pool-4-thread-50) [6eaa1a17] START, DetachStorageDomainVDSCommand( storagePoolId = b8a247a6-4515-11e2-bdd7-001a4a013f66, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 95db1074-760e-4bf4-b23e-3139be66592b, masterDomainId = 00000000-0000-0000-0000-000000000000, masterVersion = -1, force = true), log id: 7cb30672
>2012-12-13 12:32:46,956 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DetachStorageDomainVDSCommand] (pool-4-thread-50) [6eaa1a17] FINISH, DetachStorageDomainVDSCommand, log id: 7cb30672
>2012-12-13 12:32:47,007 INFO  [org.ovirt.engine.core.bll.storage.ForceRemoveStorageDomainCommand] (pool-4-thread-50) [6eaa1a17] Lock freed to object EngineLock [exclusiveLocks= key: 95db1074-760e-4bf4-b23e-3139be66592b value: STORAGE
>, sharedLocks= ]
>2012-12-13 12:32:55,658 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-8) Setting new tasks map. The map contains now 0 tasks
>2012-12-13 12:32:55,658 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-8) Cleared all tasks of pool b8a247a6-4515-11e2-bdd7-001a4a013f66.
>2012-12-13 12:32:55,662 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-8) Could not find vds that is spm and non-operational.

Actions: View

Attachments on bug 886855: 662907