Attachment 859665 Details for Bug 1061724 – engine logs

engine logs

engine.log (text/x-log), 30.73 KB, created by Gadi Ickowicz on 2014-02-05 13:50:35 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Gadi Ickowicz

Created: 2014-02-05 13:50:35 UTC

Size: 30.73 KB

patch

obsolete

>2014-02-05 15:08:00,569 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-5) Running command: LoginUserCommand internal: false.
>2014-02-05 15:08:00,803 ERROR [org.ovirt.engine.core.utils.servlet.ServletUtils] (ajp--127.0.0.1-8702-6) Can't read file "/var/lib/ovirt-engine/reports.xml" for request "/ovirt-engine/services/reports-ui", will send a 404 error response.
>2014-02-05 15:08:38,263 INFO  [org.ovirt.engine.core.bll.ChangeVDSClusterCommand] (ajp--127.0.0.1-8702-10) [355f2fa] Running command: ChangeVDSClusterCommand internal: false. Entities affected :  ID: 3a31b558-70c2-4e3b-bdae-20b0410dae75 Type: VDS,  ID: de8d037c-439a-41b0-b44a-a50569912245 Type: VdsGroups
>2014-02-05 15:08:38,271 INFO  [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp--127.0.0.1-8702-10) [355f2fa] START, RemoveVdsVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75), log id: 488488d6
>2014-02-05 15:08:38,272 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp--127.0.0.1-8702-10) [355f2fa] vdsManager::disposing
>2014-02-05 15:08:38,276 INFO  [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp--127.0.0.1-8702-10) [355f2fa] FINISH, RemoveVdsVDSCommand, log id: 488488d6
>2014-02-05 15:08:38,277 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp--127.0.0.1-8702-10) [355f2fa] Cannot get vdsManager for vdsid=3a31b558-70c2-4e3b-bdae-20b0410dae75
>2014-02-05 15:08:38,282 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-10) [355f2fa] START, AddVdsVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75), log id: 30f60348
>2014-02-05 15:08:38,283 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-10) [355f2fa] AddVds - entered , starting logic to add VDS 3a31b558-70c2-4e3b-bdae-20b0410dae75
>2014-02-05 15:08:38,285 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-10) [355f2fa] AddVds - VDS 3a31b558-70c2-4e3b-bdae-20b0410dae75 was added, will try to add it to the resource manager
>2014-02-05 15:08:38,286 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp--127.0.0.1-8702-10) [355f2fa] Entered VdsManager constructor
>2014-02-05 15:08:38,304 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp--127.0.0.1-8702-10) [355f2fa] Initialize vdsBroker (gold-vdsc.qa.lab.tlv.redhat.com,54,321)
>2014-02-05 15:08:38,309 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp--127.0.0.1-8702-10) [355f2fa] VDS 3a31b558-70c2-4e3b-bdae-20b0410dae75 was added to the Resource Manager
>2014-02-05 15:08:38,310 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-10) [355f2fa] FINISH, AddVdsVDSCommand, log id: 30f60348
>2014-02-05 15:08:38,315 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp--127.0.0.1-8702-10) [46fb84a8] Running command: AddVdsSpmIdCommand internal: true. Entities affected :  ID: 3a31b558-70c2-4e3b-bdae-20b0410dae75 Type: VDS
>2014-02-05 15:08:38,325 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp--127.0.0.1-8702-10) [46fb84a8] Lock freed to object EngineLock [exclusiveLocks= key: 947bb734-1050-4926-8a3a-03406ad80b1e value: REGISTER_VDS
>, sharedLocks= ]
>2014-02-05 15:08:38,345 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-10) [46fb84a8] Correlation ID: 355f2fa, Call Stack: null, Custom Event ID: -1, Message: Host gold-vdsc.qa.lab.tlv.redhat.com configuration was updated by admin.
>2014-02-05 15:08:38,350 INFO  [org.ovirt.engine.core.bll.network.host.PersistentSetupNetworksCommand] (org.ovirt.thread.pool-6-thread-28) [70f3d8c6] Running command: PersistentSetupNetworksCommand internal: true. Entities affected :  ID: 3a31b558-70c2-4e3b-bdae-20b0410dae75 Type: VDS
>2014-02-05 15:08:38,380 INFO  [org.ovirt.engine.core.bll.network.host.SetupNetworksCommand] (org.ovirt.thread.pool-6-thread-28) [70f3d8c6] Running command: SetupNetworksCommand internal: true. Entities affected :  ID: 3a31b558-70c2-4e3b-bdae-20b0410dae75 Type: VDS
>2014-02-05 15:08:38,388 INFO  [org.ovirt.engine.core.bll.network.host.CommitNetworkChangesCommand] (org.ovirt.thread.pool-6-thread-28) [61c9516c] Running command: CommitNetworkChangesCommand internal: true. Entities affected :  ID: 3a31b558-70c2-4e3b-bdae-20b0410dae75 Type: VDS
>2014-02-05 15:08:38,393 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetSafeNetworkConfigVDSCommand] (org.ovirt.thread.pool-6-thread-28) [61c9516c] START, SetSafeNetworkConfigVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75), log id: 310df495
>2014-02-05 15:08:38,424 WARN  [org.ovirt.engine.core.compat.backendcompat.PropertyInfo] (ajp--127.0.0.1-8702-9) Unable to get value of property: vdsName for class org.ovirt.engine.core.common.businessentities.VdsStatic
>2014-02-05 15:08:38,425 WARN  [org.ovirt.engine.core.compat.backendcompat.PropertyInfo] (ajp--127.0.0.1-8702-9) Unable to get value of property: vdsName for class org.ovirt.engine.core.common.businessentities.VdsStatic
>2014-02-05 15:08:38,433 INFO  [org.ovirt.engine.core.bll.UpdateVdsCommand] (ajp--127.0.0.1-8702-9) [4586963f] Running command: UpdateVdsCommand internal: false. Entities affected :  ID: 3a31b558-70c2-4e3b-bdae-20b0410dae75 Type: VDS
>2014-02-05 15:08:38,444 WARN  [org.ovirt.engine.core.compat.backendcompat.PropertyInfo] (ajp--127.0.0.1-8702-9) Unable to get value of property: vdsName for class org.ovirt.engine.core.common.businessentities.VdsStatic
>2014-02-05 15:08:38,445 WARN  [org.ovirt.engine.core.compat.backendcompat.PropertyInfo] (ajp--127.0.0.1-8702-9) Unable to get value of property: vdsName for class org.ovirt.engine.core.common.businessentities.VdsStatic
>2014-02-05 15:08:38,460 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-9) [4586963f] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host gold-vdsc.qa.lab.tlv.redhat.com.
>2014-02-05 15:08:38,467 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-9) [4586963f] Correlation ID: 4586963f, Call Stack: null, Custom Event ID: -1, Message: Host gold-vdsc.qa.lab.tlv.redhat.com configuration was updated by admin.
>2014-02-05 15:08:38,750 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetSafeNetworkConfigVDSCommand] (org.ovirt.thread.pool-6-thread-28) [61c9516c] FINISH, SetSafeNetworkConfigVDSCommand, log id: 310df495
>2014-02-05 15:08:38,756 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-28) [61c9516c] Correlation ID: 61c9516c, Call Stack: null, Custom Event ID: -1, Message: Network changes were saved on host gold-vdsc.qa.lab.tlv.redhat.com
>2014-02-05 15:08:40,782 INFO  [org.ovirt.engine.core.bll.ActivateVdsCommand] (ajp--127.0.0.1-8702-5) [5f40a3be] Lock Acquired to object EngineLock [exclusiveLocks= key: 3a31b558-70c2-4e3b-bdae-20b0410dae75 value: VDS
>, sharedLocks= ]
>2014-02-05 15:08:40,799 INFO  [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-6-thread-32) [5f40a3be] Running command: ActivateVdsCommand internal: false. Entities affected :  ID: 3a31b558-70c2-4e3b-bdae-20b0410dae75 Type: VDS
>2014-02-05 15:08:40,800 INFO  [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-6-thread-32) [5f40a3be] Before acquiring lock in order to prevent monitoring for host gold-vdsc.qa.lab.tlv.redhat.com from data-center glusterDC
>2014-02-05 15:08:40,801 INFO  [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-6-thread-32) [5f40a3be] Lock acquired, from now a monitoring of host will be skipped for host gold-vdsc.qa.lab.tlv.redhat.com from data-center glusterDC
>2014-02-05 15:08:40,805 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-32) [5f40a3be] START, SetVdsStatusVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75, status=Unassigned, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 4e8ea48
>2014-02-05 15:08:40,810 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-32) [5f40a3be] FINISH, SetVdsStatusVDSCommand, log id: 4e8ea48
>2014-02-05 15:08:40,813 INFO  [org.ovirt.engine.core.vdsbroker.ActivateVdsVDSCommand] (org.ovirt.thread.pool-6-thread-32) [5f40a3be] START, ActivateVdsVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75), log id: 2177e252
>2014-02-05 15:08:40,898 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (org.ovirt.thread.pool-6-thread-32) [5f40a3be] START, GetHardwareInfoVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75, vds=Host[gold-vdsc.qa.lab.tlv.redhat.com]), log id: 2c9f4951
>2014-02-05 15:08:40,912 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (org.ovirt.thread.pool-6-thread-32) [5f40a3be] FINISH, GetHardwareInfoVDSCommand, log id: 2c9f4951
>2014-02-05 15:08:40,931 INFO  [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (org.ovirt.thread.pool-6-thread-32) [25a8991] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected :  ID: 3a31b558-70c2-4e3b-bdae-20b0410dae75 Type: VDS
>2014-02-05 15:08:40,935 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (org.ovirt.thread.pool-6-thread-32) [4aa3d8f4] Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: 3a31b558-70c2-4e3b-bdae-20b0410dae75 Type: VDS
>2014-02-05 15:08:40,937 INFO  [org.ovirt.engine.core.vdsbroker.ActivateVdsVDSCommand] (org.ovirt.thread.pool-6-thread-32) [4aa3d8f4] FINISH, ActivateVdsVDSCommand, return: Host[gold-vdsc.qa.lab.tlv.redhat.com], log id: 2177e252
>2014-02-05 15:08:40,971 INFO  [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-6-thread-32) [4aa3d8f4] Activate finished. Lock released. Monitoring can run now for host gold-vdsc.qa.lab.tlv.redhat.com from data-center glusterDC
>2014-02-05 15:08:40,975 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-32) [4aa3d8f4] Correlation ID: 5f40a3be, Job ID: a0d99600-25ea-4d83-a73d-6bf02f90c639, Call Stack: null, Custom Event ID: -1, Message: Host gold-vdsc.qa.lab.tlv.redhat.com was activated by admin.
>2014-02-05 15:08:40,979 INFO  [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-6-thread-32) [4aa3d8f4] Lock freed to object EngineLock [exclusiveLocks= key: 3a31b558-70c2-4e3b-bdae-20b0410dae75 value: VDS
>, sharedLocks= ]
>2014-02-05 15:08:41,388 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-82) [47b29800] START, GetHardwareInfoVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75, vds=Host[gold-vdsc.qa.lab.tlv.redhat.com]), log id: 1369a7d4
>2014-02-05 15:08:41,398 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-82) [47b29800] FINISH, GetHardwareInfoVDSCommand, log id: 1369a7d4
>2014-02-05 15:08:41,468 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-82) [47b29800] START, GetHardwareInfoVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75, vds=Host[gold-vdsc.qa.lab.tlv.redhat.com]), log id: 15f4ea2a
>2014-02-05 15:08:41,479 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-82) [47b29800] FINISH, GetHardwareInfoVDSCommand, log id: 15f4ea2a
>2014-02-05 15:08:41,498 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-82) [47b29800] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: State was set to Up for host gold-vdsc.qa.lab.tlv.redhat.com.
>2014-02-05 15:08:41,507 INFO  [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (DefaultQuartzScheduler_Worker-82) [321cb178] Running command: InitVdsOnUpCommand internal: true. Entities affected :  ID: 947bb734-1050-4926-8a3a-03406ad80b1e Type: StoragePool
>2014-02-05 15:08:41,516 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (DefaultQuartzScheduler_Worker-82) [321cb178] START, SetMOMPolicyParametersVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75), log id: 2fb95cc5
>2014-02-05 15:08:41,601 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (DefaultQuartzScheduler_Worker-82) [321cb178] FINISH, SetMOMPolicyParametersVDSCommand, log id: 2fb95cc5
>2014-02-05 15:08:41,606 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-82) [321cb178] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host gold-vdsc.qa.lab.tlv.redhat.com.
>2014-02-05 15:08:41,612 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-82) [321cb178] Initializing Host: gold-vdsc.qa.lab.tlv.redhat.com
>2014-02-05 15:08:41,614 INFO  [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (DefaultQuartzScheduler_Worker-82) [1034b2b] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected :  ID: 3a31b558-70c2-4e3b-bdae-20b0410dae75 Type: VDS
>2014-02-05 15:08:41,620 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (DefaultQuartzScheduler_Worker-82) [a8a412] Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: 3a31b558-70c2-4e3b-bdae-20b0410dae75 Type: VDS
>2014-02-05 15:09:31,509 INFO  [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (ajp--127.0.0.1-8702-2) [67dc952e] Lock Acquired to object EngineLock [exclusiveLocks= key: orion.qa.lab:/gickowic-ovirt value: STORAGE_CONNECTION
>, sharedLocks= ]
>2014-02-05 15:09:31,517 INFO  [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (ajp--127.0.0.1-8702-2) [67dc952e] Running command: AddStorageServerConnectionCommand internal: false. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2014-02-05 15:09:31,519 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-2) [67dc952e] START, ConnectStorageServerVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = GLUSTERFS, connectionList = [{ id: null, connection: orion.qa.lab:/gickowic-ovirt, iqn: null, vfsType: glusterfs, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 35e10cd8
>2014-02-05 15:09:32,287 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-2) [67dc952e] FINISH, ConnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 35e10cd8
>2014-02-05 15:09:32,301 INFO  [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (ajp--127.0.0.1-8702-2) [67dc952e] Lock freed to object EngineLock [exclusiveLocks= key: orion.qa.lab:/gickowic-ovirt value: STORAGE_CONNECTION
>, sharedLocks= ]
>2014-02-05 15:09:32,365 WARN  [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (ajp--127.0.0.1-8702-4) [5e834490] The message key AddGlusterFsStorageDomain is missing from bundles/ExecutionMessages
>2014-02-05 15:09:32,397 INFO  [org.ovirt.engine.core.bll.storage.AddGlusterFsStorageDomainCommand] (ajp--127.0.0.1-8702-4) [5e834490] Running command: AddGlusterFsStorageDomainCommand internal: false. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2014-02-05 15:09:32,405 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-4) [5e834490] START, ConnectStorageServerVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = GLUSTERFS, connectionList = [{ id: e0905eb5-7567-4194-81d6-e44b2d6a529a, connection: orion.qa.lab:/gickowic-ovirt, iqn: null, vfsType: glusterfs, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 7d2a82f6
>2014-02-05 15:09:32,425 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-4) [5e834490] FINISH, ConnectStorageServerVDSCommand, return: {e0905eb5-7567-4194-81d6-e44b2d6a529a=0}, log id: 7d2a82f6
>2014-02-05 15:09:32,428 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (ajp--127.0.0.1-8702-4) [5e834490] START, CreateStorageDomainVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75, storageDomain=org.ovirt.engine.core.common.businessentities.StorageDomainStatic@9c9986b1, args=orion.qa.lab:/gickowic-ovirt), log id: 162430b8
>2014-02-05 15:09:34,388 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (ajp--127.0.0.1-8702-4) [5e834490] FINISH, CreateStorageDomainVDSCommand, log id: 162430b8
>2014-02-05 15:09:34,389 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (ajp--127.0.0.1-8702-4) [5e834490] START, GetStorageDomainStatsVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75, storageDomainId=99439ac2-28db-43a2-9d07-369c1dd5e734), log id: 6e672812
>2014-02-05 15:09:34,401 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (ajp--127.0.0.1-8702-4) [5e834490] FINISH, GetStorageDomainStatsVDSCommand, return: org.ovirt.engine.core.common.businessentities.StorageDomain@3519e27f, log id: 6e672812
>2014-02-05 15:09:34,424 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) [5e834490] Correlation ID: 5e834490, Job ID: 458c5ae3-1953-4d1e-8ed1-e1f7d7fdc55d, Call Stack: null, Custom Event ID: -1, Message: Storage Domain test was added by admin
>2014-02-05 15:09:34,570 INFO  [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] (ajp--127.0.0.1-8702-10) [7aba25f] Running command: AttachStorageDomainToPoolCommand internal: false. Entities affected :  ID: 99439ac2-28db-43a2-9d07-369c1dd5e734 Type: Storage
>2014-02-05 15:09:34,572 INFO  [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (ajp--127.0.0.1-8702-10) [42c853cb] Lock Acquired to object EngineLock [exclusiveLocks= key: 947bb734-1050-4926-8a3a-03406ad80b1e value: POOL
>, sharedLocks= ]
>2014-02-05 15:09:34,577 INFO  [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (ajp--127.0.0.1-8702-10) [42c853cb] Running command: AddStoragePoolWithStoragesCommand internal: true. Entities affected :  ID: 947bb734-1050-4926-8a3a-03406ad80b1e Type: StoragePool
>2014-02-05 15:09:34,645 INFO  [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (ajp--127.0.0.1-8702-10) [43756d15] Running command: ConnectStorageToVdsCommand internal: true. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2014-02-05 15:09:34,649 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-10) [43756d15] START, ConnectStorageServerVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = GLUSTERFS, connectionList = [{ id: e0905eb5-7567-4194-81d6-e44b2d6a529a, connection: orion.qa.lab:/gickowic-ovirt, iqn: null, vfsType: glusterfs, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 4b90fd47
>2014-02-05 15:09:34,668 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-10) [43756d15] FINISH, ConnectStorageServerVDSCommand, return: {e0905eb5-7567-4194-81d6-e44b2d6a529a=0}, log id: 4b90fd47
>2014-02-05 15:09:34,671 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStoragePoolVDSCommand] (ajp--127.0.0.1-8702-10) [43756d15] START, CreateStoragePoolVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75, storagePoolId=947bb734-1050-4926-8a3a-03406ad80b1e, storageType=GLUSTERFS, storagePoolName=glusterDC, masterDomainId=99439ac2-28db-43a2-9d07-369c1dd5e734, domainsIdList=[99439ac2-28db-43a2-9d07-369c1dd5e734], masterVersion=1), log id: 7aeea22
>2014-02-05 15:09:59,741 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStoragePoolVDSCommand] (ajp--127.0.0.1-8702-10) [43756d15] FINISH, CreateStoragePoolVDSCommand, log id: 7aeea22
>2014-02-05 15:09:59,745 INFO  [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (ajp--127.0.0.1-8702-10) [43756d15] Lock freed to object EngineLock [exclusiveLocks= key: 947bb734-1050-4926-8a3a-03406ad80b1e value: POOL
>, sharedLocks= ]
>2014-02-05 15:09:59,746 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-10) [198a40ab] Lock Acquired to object EngineLock [exclusiveLocks= key: 99439ac2-28db-43a2-9d07-369c1dd5e734 value: STORAGE
>, sharedLocks= ]
>2014-02-05 15:09:59,752 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-10) [198a40ab] Running command: ActivateStorageDomainCommand internal: true. Entities affected :  ID: 99439ac2-28db-43a2-9d07-369c1dd5e734 Type: Storage
>2014-02-05 15:09:59,757 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-10) [198a40ab] Lock freed to object EngineLock [exclusiveLocks= key: 99439ac2-28db-43a2-9d07-369c1dd5e734 value: STORAGE
>, sharedLocks= ]
>2014-02-05 15:09:59,758 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-10) [198a40ab] ActivateStorage Domain. Before Connect all hosts to pool. Time:2/5/14 3:09 PM
>2014-02-05 15:09:59,762 INFO  [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (org.ovirt.thread.pool-6-thread-25) [5c7f6ab5] Running command: ConnectStorageToVdsCommand internal: true. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2014-02-05 15:09:59,769 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-6-thread-25) [5c7f6ab5] START, ConnectStorageServerVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = GLUSTERFS, connectionList = [{ id: e0905eb5-7567-4194-81d6-e44b2d6a529a, connection: orion.qa.lab:/gickowic-ovirt, iqn: null, vfsType: glusterfs, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 3fe830fd
>2014-02-05 15:09:59,792 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-6-thread-25) [5c7f6ab5] FINISH, ConnectStorageServerVDSCommand, return: {e0905eb5-7567-4194-81d6-e44b2d6a529a=0}, log id: 3fe830fd
>2014-02-05 15:09:59,794 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (ajp--127.0.0.1-8702-10) [198a40ab] START, ActivateStorageDomainVDSCommand( storagePoolId = 947bb734-1050-4926-8a3a-03406ad80b1e, ignoreFailoverLimit = false, storageDomainId = 99439ac2-28db-43a2-9d07-369c1dd5e734), log id: 126a7149
>2014-02-05 15:09:59,803 INFO  [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (ajp--127.0.0.1-8702-10) [46c3c546] Running command: SetStoragePoolStatusCommand internal: true. Entities affected :  ID: 947bb734-1050-4926-8a3a-03406ad80b1e Type: StoragePool
>2014-02-05 15:09:59,814 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-10) [46c3c546] Correlation ID: 46c3c546, Call Stack: null, Custom Event ID: -1, Message: Data Center is being initialized, please wait for initialization to complete.
>2014-02-05 15:09:59,827 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (ajp--127.0.0.1-8702-10) [46c3c546] hostFromVds::selectedVds - gold-vdsc.qa.lab.tlv.redhat.com, spmStatus Unknown_Pool, storage pool glusterDC
>2014-02-05 15:09:59,834 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (ajp--127.0.0.1-8702-10) [46c3c546] START, ConnectStoragePoolVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75, storagePoolId = 947bb734-1050-4926-8a3a-03406ad80b1e, vds_spm_id = 1, masterDomainId = 99439ac2-28db-43a2-9d07-369c1dd5e734, masterVersion = 1), log id: 48d55246
>2014-02-05 15:10:01,232 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (ajp--127.0.0.1-8702-10) [46c3c546] FINISH, ConnectStoragePoolVDSCommand, log id: 48d55246
>2014-02-05 15:10:01,299 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (ajp--127.0.0.1-8702-10) [46c3c546] hostFromVds::Connected host to pool - selectedVds - gold-vdsc.qa.lab.tlv.redhat.com, spmStatus Free, storage pool glusterDC
>2014-02-05 15:10:01,304 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (ajp--127.0.0.1-8702-10) [46c3c546] starting spm on vds gold-vdsc.qa.lab.tlv.redhat.com, storage pool glusterDC, prevId -1, LVER -1
>2014-02-05 15:10:01,310 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp--127.0.0.1-8702-10) [46c3c546] START, SpmStartVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75, storagePoolId = 947bb734-1050-4926-8a3a-03406ad80b1e, prevId=-1, prevLVER=-1, storagePoolFormatType=V3, recoveryMode=Manual, SCSIFencing=false), log id: 5a3bbce6
>2014-02-05 15:10:01,324 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp--127.0.0.1-8702-10) [46c3c546] spmStart polling started: taskId = 0e066db6-4d75-45b8-8f07-729d285e0fda
>2014-02-05 15:10:23,543 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp--127.0.0.1-8702-10) [46c3c546] spmStart polling ended: taskId = 0e066db6-4d75-45b8-8f07-729d285e0fda task status = finished
>2014-02-05 15:10:23,598 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp--127.0.0.1-8702-10) [46c3c546] spmStart polling ended, spm status: SPM
>2014-02-05 15:10:23,604 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (ajp--127.0.0.1-8702-10) [46c3c546] START, HSMClearTaskVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75, taskId=0e066db6-4d75-45b8-8f07-729d285e0fda), log id: 3a862d34
>2014-02-05 15:10:23,613 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (ajp--127.0.0.1-8702-10) [46c3c546] FINISH, HSMClearTaskVDSCommand, log id: 3a862d34
>2014-02-05 15:10:23,613 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp--127.0.0.1-8702-10) [46c3c546] FINISH, SpmStartVDSCommand, return: org.ovirt.engine.core.common.businessentities.SpmStatusResult@3cbd6e4f, log id: 5a3bbce6
>2014-02-05 15:10:23,616 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (ajp--127.0.0.1-8702-10) [46c3c546] Initialize Irs proxy from vds: gold-vdsc.qa.lab.tlv.redhat.com
>2014-02-05 15:10:23,622 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-10) [46c3c546] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Storage Pool Manager runs on Host gold-vdsc.qa.lab.tlv.redhat.com (Address: gold-vdsc.qa.lab.tlv.redhat.com).
>2014-02-05 15:10:23,637 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (ajp--127.0.0.1-8702-10) [46c3c546] FINISH, ActivateStorageDomainVDSCommand, log id: 126a7149
>2014-02-05 15:10:23,638 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-10) [46c3c546] ActivateStorage Domain. After Connect all hosts to pool. Time:2/5/14 3:10 PM
>2014-02-05 15:10:23,644 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-6-thread-30) [46c3c546] START, ConnectStoragePoolVDSCommand(HostName = gold-vdsc.qa.lab.tlv.redhat.com, HostId = 3a31b558-70c2-4e3b-bdae-20b0410dae75, storagePoolId = 947bb734-1050-4926-8a3a-03406ad80b1e, vds_spm_id = 1, masterDomainId = 99439ac2-28db-43a2-9d07-369c1dd5e734, masterVersion = 1), log id: 6c9ee099
>2014-02-05 15:10:25,108 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-6-thread-30) [46c3c546] FINISH, ConnectStoragePoolVDSCommand, log id: 6c9ee099
>2014-02-05 15:10:25,119 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-10) [46c3c546] ActivateStorage Domain. After change storage pool status in vds. Time:2/5/14 3:10 PM
>2014-02-05 15:10:25,130 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-10) [46c3c546] Correlation ID: 198a40ab, Call Stack: null, Custom Event ID: -1, Message: Storage Domain test (Data Center glusterDC) was activated by admin
>2014-02-05 15:10:25,147 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-10) [46c3c546] Correlation ID: 42c853cb, Call Stack: null, Custom Event ID: -1, Message: Storage Domains were attached to Data Center glusterDC by admin
>2014-02-05 15:10:25,163 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-10) [46c3c546] Correlation ID: 7aba25f, Job ID: 31d91e99-f224-4c58-a5c4-58bf648a603a, Call Stack: null, Custom Event ID: -1, Message: Storage Domain test was attached to Data Center glusterDC by admin
>2014-02-05 15:13:29,239 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-78) Attempting to update VMs/Templates Ovf.
>2014-02-05 15:13:29,241 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-78) Attempting to update VM OVFs in Data Center glusterDC
>2014-02-05 15:13:29,251 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-78) Successfully updated VM OVFs in Data Center glusterDC
>2014-02-05 15:13:29,252 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-78) Attempting to update template OVFs in Data Center glusterDC
>2014-02-05 15:13:29,258 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-78) Successfully updated templates OVFs in Data Center glusterDC
>2014-02-05 15:13:29,258 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-78) Attempting to remove unneeded template/vm OVFs in Data Center glusterDC
>2014-02-05 15:13:29,260 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-78) Successfully removed unneeded template/vm OVFs in Data Center glusterDC
>2014-02-05 15:13:30,093 INFO  [org.ovirt.engine.core.bll.LogoutUserCommand] (ajp--127.0.0.1-8702-6) [191dc56f] Running command: LogoutUserCommand internal: false.
>2014-02-05 15:13:30,098 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-6) [191dc56f] Correlation ID: 191dc56f, Call Stack: null, Custom Event ID: -1, Message: User admin logged out.

Actions: View

Attachments on bug 1061724: 859665