Attachment 704089 Details for Bug 916485 – rhevm.log

rhevm.log

rhevm.log (text/plain), 168.99 KB, created by cshao on 2013-03-01 08:49:42 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: cshao

Created: 2013-03-01 08:49:42 UTC

Size: 168.99 KB

patch

obsolete

>2013-02-21 09:54:07,569 INFO  [org.ovirt.engine.core.bll.Backend] (main) Start time: 2/21/13 9:54 AM
>2013-02-21 09:54:07,709 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: CARhevmKey
>2013-02-21 09:54:07,833 ERROR [org.ovirt.engine.core.rhevmencryptutils.EncryptionUtils] (main) Failed to decrypt
>2013-02-21 09:54:07,833 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (main) Failed to decrypt value for property TruststorePass will be used encrypted value
>2013-02-21 09:54:07,869 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: CbcCheckOnVdsChange
>2013-02-21 09:54:09,702 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: ScriptsPath
>2013-02-21 09:54:09,703 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: SQLServerI18NPrefix
>2013-02-21 09:54:09,723 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: MinimalETLVersion
>2013-02-21 09:54:09,723 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: RHEVMEARLib
>2013-02-21 09:54:09,742 INFO  [org.ovirt.engine.core.bll.Backend] (main) VDSBrokerFrontend: 2/21/13 9:54 AM
>2013-02-21 09:54:09,748 INFO  [org.ovirt.engine.core.bll.Backend] (main) CpuFlagsManager: 2/21/13 9:54 AM
>2013-02-21 09:54:09,750 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (main) Setting audit clean up manager to run at: 35 35 3 * * ?
>2013-02-21 09:54:09,847 INFO  [org.ovirt.engine.core.bll.Backend] (main) AuditLogCleanupManager: 2/21/13 9:54 AM
>2013-02-21 09:54:09,873 INFO  [org.ovirt.engine.core.bll.TagsDirector] (main) TagsDirector initialization
>2013-02-21 09:54:09,893 INFO  [org.ovirt.engine.core.bll.TagsDirector] (main) Tag root added to tree
>2013-02-21 09:54:09,920 INFO  [org.ovirt.engine.core.bll.Backend] (main) TagsDirector: 2/21/13 9:54 AM
>2013-02-21 09:54:09,927 INFO  [org.ovirt.engine.core.bll.Backend] (main) ImagesSyncronizer: 2/21/13 9:54 AM
>2013-02-21 09:54:09,934 INFO  [org.ovirt.engine.core.bll.Backend] (main) IsoDomainListSyncronizer: 2/21/13 9:54 AM
>2013-02-21 09:54:09,976 INFO  [org.ovirt.engine.core.bll.Backend] (main) InitHandlers: 2/21/13 9:54 AM
>2013-02-21 09:54:10,001 INFO  [org.ovirt.engine.core.bll.Backend] (main) ErrorTranslator: 2/21/13 9:54 AM
>2013-02-21 09:54:10,002 INFO  [org.ovirt.engine.core.bll.Backend] (main) VdsErrorTranslator: 2/21/13 9:54 AM
>2013-02-21 09:54:10,114 INFO  [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (main) UsersDomainsCacheManager: 2/21/13 9:54 AM
>2013-02-21 09:54:10,149 INFO  [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (main) DbUserCacheManager: 2/21/13 9:54 AM
>2013-02-21 09:54:10,243 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-1) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-21 09:54:10,291 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) InitResourceManager: 2/21/13 9:54 AM
>2013-02-21 09:54:10,309 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (main) ResourceManager::ResourceManager::Entered
>2013-02-21 09:54:10,650 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (main) AsyncTaskManager: Initialization of AsyncTaskManager completed successfully.
>2013-02-21 09:54:10,650 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) AsyncTaskManager: 2/21/13 9:54 AM
>2013-02-21 09:54:10,659 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) VdsLoadBalancer: 2/21/13 9:54 AM
>2013-02-21 09:54:10,725 INFO  [org.ovirt.engine.core.bll.TimeLeasedVmPoolManager] (main) TimeLeasedVmPoolManager constractor entered
>2013-02-21 09:54:10,726 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) TimeLeasedVmPoolManager: 2/21/13 9:54 AM
>2013-02-21 09:54:10,773 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) MacPoolManager: 2/21/13 9:54 AM
>2013-02-21 10:27:39,707 INFO  [org.ovirt.engine.core.bll.Backend] (main) Start time: 2/21/13 10:27 AM
>2013-02-21 10:27:40,015 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: CARhevmKey
>2013-02-21 10:27:40,166 ERROR [org.ovirt.engine.core.rhevmencryptutils.EncryptionUtils] (main) Failed to decrypt
>2013-02-21 10:27:40,166 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (main) Failed to decrypt value for property TruststorePass will be used encrypted value
>2013-02-21 10:27:40,197 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: CbcCheckOnVdsChange
>2013-02-21 10:27:42,091 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: ScriptsPath
>2013-02-21 10:27:42,092 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: SQLServerI18NPrefix
>2013-02-21 10:27:42,099 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: MinimalETLVersion
>2013-02-21 10:27:42,099 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: RHEVMEARLib
>2013-02-21 10:27:42,113 INFO  [org.ovirt.engine.core.bll.Backend] (main) VDSBrokerFrontend: 2/21/13 10:27 AM
>2013-02-21 10:27:42,134 INFO  [org.ovirt.engine.core.bll.Backend] (main) CpuFlagsManager: 2/21/13 10:27 AM
>2013-02-21 10:27:42,136 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (main) Setting audit clean up manager to run at: 35 35 3 * * ?
>2013-02-21 10:27:42,233 INFO  [org.ovirt.engine.core.bll.Backend] (main) AuditLogCleanupManager: 2/21/13 10:27 AM
>2013-02-21 10:27:42,244 INFO  [org.ovirt.engine.core.bll.TagsDirector] (main) TagsDirector initialization
>2013-02-21 10:27:42,275 INFO  [org.ovirt.engine.core.bll.TagsDirector] (main) Tag root added to tree
>2013-02-21 10:27:42,313 INFO  [org.ovirt.engine.core.bll.Backend] (main) TagsDirector: 2/21/13 10:27 AM
>2013-02-21 10:27:42,320 INFO  [org.ovirt.engine.core.bll.Backend] (main) ImagesSyncronizer: 2/21/13 10:27 AM
>2013-02-21 10:27:42,327 INFO  [org.ovirt.engine.core.bll.Backend] (main) IsoDomainListSyncronizer: 2/21/13 10:27 AM
>2013-02-21 10:27:42,371 INFO  [org.ovirt.engine.core.bll.Backend] (main) InitHandlers: 2/21/13 10:27 AM
>2013-02-21 10:27:42,394 INFO  [org.ovirt.engine.core.bll.Backend] (main) ErrorTranslator: 2/21/13 10:27 AM
>2013-02-21 10:27:42,394 INFO  [org.ovirt.engine.core.bll.Backend] (main) VdsErrorTranslator: 2/21/13 10:27 AM
>2013-02-21 10:27:42,493 INFO  [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (main) UsersDomainsCacheManager: 2/21/13 10:27 AM
>2013-02-21 10:27:42,579 INFO  [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (main) DbUserCacheManager: 2/21/13 10:27 AM
>2013-02-21 10:27:42,682 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-1) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-21 10:27:42,754 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) InitResourceManager: 2/21/13 10:27 AM
>2013-02-21 10:27:42,772 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (main) ResourceManager::ResourceManager::Entered
>2013-02-21 10:27:43,013 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (main) AsyncTaskManager: Initialization of AsyncTaskManager completed successfully.
>2013-02-21 10:27:43,014 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) AsyncTaskManager: 2/21/13 10:27 AM
>2013-02-21 10:27:43,019 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) VdsLoadBalancer: 2/21/13 10:27 AM
>2013-02-21 10:27:43,047 INFO  [org.ovirt.engine.core.bll.TimeLeasedVmPoolManager] (main) TimeLeasedVmPoolManager constractor entered
>2013-02-21 10:27:43,048 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) TimeLeasedVmPoolManager: 2/21/13 10:27 AM
>2013-02-21 10:27:43,062 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) MacPoolManager: 2/21/13 10:27 AM
>2013-02-21 13:16:55,211 INFO  [org.ovirt.engine.core.bll.Backend] (main) Start time: 2/21/13 1:16 PM
>2013-02-21 13:16:55,542 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: CARhevmKey
>2013-02-21 13:16:55,883 ERROR [org.ovirt.engine.core.rhevmencryptutils.EncryptionUtils] (main) Failed to decrypt
>2013-02-21 13:16:55,883 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (main) Failed to decrypt value for property TruststorePass will be used encrypted value
>2013-02-21 13:16:55,900 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: CbcCheckOnVdsChange
>2013-02-21 13:16:57,213 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: ScriptsPath
>2013-02-21 13:16:57,218 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: SQLServerI18NPrefix
>2013-02-21 13:16:57,242 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: MinimalETLVersion
>2013-02-21 13:16:57,242 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: RHEVMEARLib
>2013-02-21 13:16:58,569 INFO  [org.ovirt.engine.core.bll.Backend] (main) VDSBrokerFrontend: 2/21/13 1:16 PM
>2013-02-21 13:16:58,577 INFO  [org.ovirt.engine.core.bll.Backend] (main) CpuFlagsManager: 2/21/13 1:16 PM
>2013-02-21 13:16:58,579 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (main) Setting audit clean up manager to run at: 35 35 3 * * ?
>2013-02-21 13:16:58,624 INFO  [org.ovirt.engine.core.bll.Backend] (main) AuditLogCleanupManager: 2/21/13 1:16 PM
>2013-02-21 13:16:58,910 INFO  [org.ovirt.engine.core.bll.TagsDirector] (main) TagsDirector initialization
>2013-02-21 13:16:58,947 INFO  [org.ovirt.engine.core.bll.TagsDirector] (main) Tag root added to tree
>2013-02-21 13:16:59,123 INFO  [org.ovirt.engine.core.bll.Backend] (main) TagsDirector: 2/21/13 1:16 PM
>2013-02-21 13:16:59,140 INFO  [org.ovirt.engine.core.bll.Backend] (main) ImagesSyncronizer: 2/21/13 1:16 PM
>2013-02-21 13:16:59,164 INFO  [org.ovirt.engine.core.bll.Backend] (main) IsoDomainListSyncronizer: 2/21/13 1:16 PM
>2013-02-21 13:16:59,245 INFO  [org.ovirt.engine.core.bll.Backend] (main) InitHandlers: 2/21/13 1:16 PM
>2013-02-21 13:16:59,291 INFO  [org.ovirt.engine.core.bll.Backend] (main) ErrorTranslator: 2/21/13 1:16 PM
>2013-02-21 13:16:59,293 INFO  [org.ovirt.engine.core.bll.Backend] (main) VdsErrorTranslator: 2/21/13 1:16 PM
>2013-02-21 13:16:59,579 INFO  [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (main) UsersDomainsCacheManager: 2/21/13 1:16 PM
>2013-02-21 13:17:00,099 INFO  [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (main) DbUserCacheManager: 2/21/13 1:17 PM
>2013-02-21 13:17:00,263 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-1) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-21 13:17:00,401 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) InitResourceManager: 2/21/13 1:17 PM
>2013-02-21 13:17:00,437 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (main) ResourceManager::ResourceManager::Entered
>2013-02-21 13:17:02,631 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (main) AsyncTaskManager: Initialization of AsyncTaskManager completed successfully.
>2013-02-21 13:17:02,631 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) AsyncTaskManager: 2/21/13 1:17 PM
>2013-02-21 13:17:02,640 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) VdsLoadBalancer: 2/21/13 1:17 PM
>2013-02-21 13:17:02,709 INFO  [org.ovirt.engine.core.bll.TimeLeasedVmPoolManager] (main) TimeLeasedVmPoolManager constractor entered
>2013-02-21 13:17:02,711 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) TimeLeasedVmPoolManager: 2/21/13 1:17 PM
>2013-02-21 13:17:02,741 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) MacPoolManager: 2/21/13 1:17 PM
>2013-02-21 14:01:45,556 INFO  [org.ovirt.engine.core.bll.Backend] (main) Start time: 2/21/13 2:01 PM
>2013-02-21 14:01:45,916 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: CARhevmKey
>2013-02-21 14:01:46,067 ERROR [org.ovirt.engine.core.rhevmencryptutils.EncryptionUtils] (main) Failed to decrypt
>2013-02-21 14:01:46,067 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (main) Failed to decrypt value for property TruststorePass will be used encrypted value
>2013-02-21 14:01:46,097 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: CbcCheckOnVdsChange
>2013-02-21 14:01:47,228 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: ScriptsPath
>2013-02-21 14:01:47,236 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: SQLServerI18NPrefix
>2013-02-21 14:01:47,255 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: MinimalETLVersion
>2013-02-21 14:01:47,255 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (main) Could not find enum value for option: RHEVMEARLib
>2013-02-21 14:01:48,551 INFO  [org.ovirt.engine.core.bll.Backend] (main) VDSBrokerFrontend: 2/21/13 2:01 PM
>2013-02-21 14:01:48,563 INFO  [org.ovirt.engine.core.bll.Backend] (main) CpuFlagsManager: 2/21/13 2:01 PM
>2013-02-21 14:01:48,565 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (main) Setting audit clean up manager to run at: 35 35 3 * * ?
>2013-02-21 14:01:48,672 INFO  [org.ovirt.engine.core.bll.Backend] (main) AuditLogCleanupManager: 2/21/13 2:01 PM
>2013-02-21 14:01:48,716 INFO  [org.ovirt.engine.core.bll.TagsDirector] (main) TagsDirector initialization
>2013-02-21 14:01:48,755 INFO  [org.ovirt.engine.core.bll.TagsDirector] (main) Tag root added to tree
>2013-02-21 14:01:48,794 INFO  [org.ovirt.engine.core.bll.Backend] (main) TagsDirector: 2/21/13 2:01 PM
>2013-02-21 14:01:48,801 INFO  [org.ovirt.engine.core.bll.Backend] (main) ImagesSyncronizer: 2/21/13 2:01 PM
>2013-02-21 14:01:48,809 INFO  [org.ovirt.engine.core.bll.Backend] (main) IsoDomainListSyncronizer: 2/21/13 2:01 PM
>2013-02-21 14:01:48,872 INFO  [org.ovirt.engine.core.bll.Backend] (main) InitHandlers: 2/21/13 2:01 PM
>2013-02-21 14:01:48,908 INFO  [org.ovirt.engine.core.bll.Backend] (main) ErrorTranslator: 2/21/13 2:01 PM
>2013-02-21 14:01:48,909 INFO  [org.ovirt.engine.core.bll.Backend] (main) VdsErrorTranslator: 2/21/13 2:01 PM
>2013-02-21 14:01:49,026 INFO  [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (main) UsersDomainsCacheManager: 2/21/13 2:01 PM
>2013-02-21 14:01:49,069 INFO  [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (main) DbUserCacheManager: 2/21/13 2:01 PM
>2013-02-21 14:01:49,194 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-1) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-21 14:01:49,283 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) InitResourceManager: 2/21/13 2:01 PM
>2013-02-21 14:01:49,326 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (main) ResourceManager::ResourceManager::Entered
>2013-02-21 14:01:49,631 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (main) AsyncTaskManager: Initialization of AsyncTaskManager completed successfully.
>2013-02-21 14:01:49,631 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) AsyncTaskManager: 2/21/13 2:01 PM
>2013-02-21 14:01:49,633 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) VdsLoadBalancer: 2/21/13 2:01 PM
>2013-02-21 14:01:49,669 INFO  [org.ovirt.engine.core.bll.TimeLeasedVmPoolManager] (main) TimeLeasedVmPoolManager constractor entered
>2013-02-21 14:01:49,670 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) TimeLeasedVmPoolManager: 2/21/13 2:01 PM
>2013-02-21 14:01:49,690 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (main) MacPoolManager: 2/21/13 2:01 PM
>2013-02-21 14:18:09,073 INFO  [org.ovirt.engine.core.bll.LoginAdminUserCommand] (http-0.0.0.0-8443-3) Running command: LoginAdminUserCommand internal: false.
>2013-02-21 14:18:09,151 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http-0.0.0.0-8443-3) AuditLogType: UNASSIGNED not exist in string table
>2013-02-21 14:18:09,155 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http-0.0.0.0-8443-3) AuditLogType: USER_UPDATE_VM_CLUSTER_DEFAULT_HOST_CLEARED not have severity. Assumed Normal
>2013-02-21 14:18:09,156 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http-0.0.0.0-8443-3) AuditLogType: USER_REMOVE_VM_TEMPLATE_FINISHED not have severity. Assumed Normal
>2013-02-21 14:18:09,156 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http-0.0.0.0-8443-3) AuditLogType: VM_IMPORT_INFO not have severity. Assumed Normal
>2013-02-21 14:18:09,156 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http-0.0.0.0-8443-3) AuditLogType: SYSTEM_UPDATE_VDS_GROUP not have severity. Assumed Normal
>2013-02-21 14:18:09,156 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http-0.0.0.0-8443-3) AuditLogType: SYSTEM_UPDATE_VDS_GROUP_FAILED not have severity. Assumed Normal
>2013-02-21 14:18:09,157 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http-0.0.0.0-8443-3) AuditLogType: SYSTEM_CHANGE_STORAGE_POOL_STATUS_RESET_IRS not have severity. Assumed Normal
>2013-02-21 14:18:09,596 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:09,597 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:09,704 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:09,706 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:09,728 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:09,731 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:09,761 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:09,817 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:09,882 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:09,883 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:09,916 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:09,939 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:09,948 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:09,993 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:10,045 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:10,087 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:10,113 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:10,120 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:10,170 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:10,201 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:10,206 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:10,242 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:10,245 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:18:10,800 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http-0.0.0.0-8443-3) Adding new value to configuration cache.
>2013-02-21 14:18:10,801 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http-0.0.0.0-8443-3) Didnt find the value of DBPagingType in DB for version general - using default: Offset
>2013-02-21 14:18:10,807 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http-0.0.0.0-8443-3) Adding new value to configuration cache.
>2013-02-21 14:18:10,812 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http-0.0.0.0-8443-3) Didnt find the value of DBPagingSyntax in DB for version general - using default: OFFSET (%1$s -1) LIMIT %2$s
>2013-02-21 14:18:10,812 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http-0.0.0.0-8443-3) Adding new value to configuration cache.
>2013-02-21 14:18:10,813 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http-0.0.0.0-8443-3) Didnt find the value of DBSearchTemplate in DB for version general - using default: SELECT * FROM (%2$s) %1$s) as T1 %3$s
>2013-02-21 14:20:57,507 INFO  [org.ovirt.engine.core.bll.LoginAdminUserCommand] (http-0.0.0.0-8443-1) Running command: LoginAdminUserCommand internal: false.
>2013-02-21 14:20:57,715 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:57,720 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:57,782 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:57,786 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:57,812 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:57,814 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:57,846 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:57,847 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:57,886 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:57,888 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:57,961 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:58,002 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:58,024 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:58,029 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:58,056 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:58,059 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:58,093 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:58,134 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:58,138 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:58,170 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:58,173 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:58,198 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:20:58,232 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-21 14:22:31,751 WARN  [org.ovirt.engine.core.bll.BackendCallBacksDirector] (pool-19-thread-50) Client did not poll async queries updates for 30 cycles, suspending server side updates for session id = FB97774869EC2678B3A24460199F09CC
>2013-02-21 14:22:32,752 WARN  [org.ovirt.engine.core.bll.BackendCallBacksDirector] (pool-19-thread-50) Client did not poll async queries updates for 30 cycles sessionId = FB97774869EC2678B3A24460199F09CC. Callback will be removed.
>2013-02-21 15:01:49,232 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-2) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-21 15:02:20,112 WARN  [org.ovirt.engine.core.bll.BackendCallBacksDirector] (pool-19-thread-49) Client did not poll async queries updates for 30 cycles, suspending server side updates for session id = 5BE8399C8507BE4351806766F371502D
>2013-02-21 15:02:21,115 WARN  [org.ovirt.engine.core.bll.BackendCallBacksDirector] (pool-19-thread-49) Client did not poll async queries updates for 30 cycles sessionId = 5BE8399C8507BE4351806766F371502D. Callback will be removed.
>2013-02-21 16:01:49,236 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-95) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-21 17:01:49,241 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-66) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-21 18:01:49,244 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-75) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-21 19:01:49,249 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-82) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-21 20:01:49,252 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-49) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-21 21:01:49,256 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-31) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-21 22:01:49,261 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-32) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-21 23:01:49,264 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-43) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 00:01:49,268 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-48) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 01:01:49,272 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-72) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 02:01:49,276 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-100) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 03:01:49,280 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-33) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 03:35:35,004 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (QuartzScheduler_Worker-58) AuditLogCleanupManager::deleteAgedOutAuditLogs - entered
>2013-02-22 04:01:49,285 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-14) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 05:01:49,291 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-8) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 06:01:49,295 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-16) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 07:01:49,300 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-79) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 08:01:49,303 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-12) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 09:01:49,308 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-80) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 10:01:49,312 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-35) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 11:01:49,317 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-94) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 12:01:49,322 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-75) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 13:01:49,326 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-70) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 14:01:49,331 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-51) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 15:01:49,335 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-15) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 16:01:49,340 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-54) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 17:01:49,344 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-90) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 18:01:49,348 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-14) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 19:01:49,351 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-9) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 20:01:49,356 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-87) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 21:01:49,359 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-40) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 22:01:49,364 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-11) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-22 23:01:49,369 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-89) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 00:01:49,374 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-42) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 01:01:49,380 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-52) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 02:01:49,385 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-6) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 03:01:49,390 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-45) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 03:35:35,003 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (QuartzScheduler_Worker-81) AuditLogCleanupManager::deleteAgedOutAuditLogs - entered
>2013-02-23 04:01:49,396 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-79) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 05:01:49,400 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-74) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 06:01:49,404 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-24) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 07:01:49,410 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-13) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 08:01:49,416 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-7) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 09:01:49,421 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-43) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 10:01:49,426 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-45) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 11:01:49,432 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-46) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 12:01:49,437 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-58) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 13:01:49,442 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-40) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 14:01:49,448 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-55) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 15:01:49,453 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-15) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 16:01:49,458 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-71) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 17:01:49,463 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-25) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 18:01:49,469 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-8) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 19:01:49,474 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-60) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 20:01:49,480 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-29) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 21:01:49,486 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-49) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 22:01:49,491 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-85) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-23 23:01:49,497 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-73) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 00:01:49,502 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-38) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 01:01:49,507 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-99) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 02:01:49,516 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-64) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 03:01:49,521 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-19) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 03:35:35,003 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (QuartzScheduler_Worker-13) AuditLogCleanupManager::deleteAgedOutAuditLogs - entered
>2013-02-24 04:01:49,526 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-16) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 05:01:49,532 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-40) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 06:01:49,537 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-54) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 07:01:49,541 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-21) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 08:01:49,546 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-58) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 09:01:49,551 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-52) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 10:01:49,556 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-93) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 11:01:49,561 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-30) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 12:01:49,567 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-70) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 13:01:49,573 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-21) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 14:01:49,578 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-95) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 15:01:49,583 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-40) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 16:01:49,588 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-77) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 17:01:49,594 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-11) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 18:01:49,599 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-18) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 19:01:49,605 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-91) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 20:01:49,610 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-29) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 21:01:49,616 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-22) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 22:01:49,621 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-16) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-24 23:01:49,626 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-74) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 00:01:49,631 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-15) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 01:01:49,636 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-76) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 02:01:49,642 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-49) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 03:01:49,647 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-96) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 03:35:35,002 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (QuartzScheduler_Worker-3) AuditLogCleanupManager::deleteAgedOutAuditLogs - entered
>2013-02-25 04:01:49,652 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-20) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 05:01:49,658 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-95) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 06:01:49,664 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-55) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 07:01:49,669 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-16) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 08:01:49,674 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-84) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 09:01:49,679 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-81) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 10:01:49,684 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-90) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 11:01:49,689 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-38) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 12:01:49,695 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-52) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 13:01:49,701 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-9) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 14:01:49,705 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-46) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 15:01:49,709 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-49) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 16:01:49,715 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-21) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 17:01:49,720 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-2) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 18:01:49,723 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-98) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 19:01:49,728 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-38) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 20:01:49,734 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-30) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 21:01:49,739 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-86) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 22:01:49,745 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-95) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-25 23:01:49,750 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-13) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 00:01:49,756 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-78) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 01:01:49,761 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-53) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 02:01:49,767 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-40) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 03:01:49,773 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-80) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 03:35:35,002 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (QuartzScheduler_Worker-93) AuditLogCleanupManager::deleteAgedOutAuditLogs - entered
>2013-02-26 04:01:49,778 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-100) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 05:01:49,784 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-88) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 06:01:49,790 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-42) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 07:01:49,795 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-74) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 08:01:49,800 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-71) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 09:01:49,805 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-97) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 10:01:49,811 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-22) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 11:01:49,816 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-7) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 12:01:49,821 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-90) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 13:01:49,825 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-80) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 14:01:49,830 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-1) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 15:01:49,835 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-70) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 16:01:49,842 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-40) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 17:01:49,847 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-40) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 18:01:49,854 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-17) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 19:01:49,859 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-59) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 20:01:49,864 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-58) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 21:01:49,869 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-82) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 22:01:49,873 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-82) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-26 23:01:49,880 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-28) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 00:01:49,886 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-28) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 01:01:49,891 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-70) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 02:01:49,896 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-26) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 03:01:49,902 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-94) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 03:35:35,002 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (QuartzScheduler_Worker-9) AuditLogCleanupManager::deleteAgedOutAuditLogs - entered
>2013-02-27 04:01:49,907 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-55) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 05:01:49,914 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-58) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 06:01:49,919 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-25) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 07:01:49,925 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-84) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 08:01:49,930 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-46) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 09:01:49,936 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-45) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 10:01:49,941 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-89) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 11:01:49,946 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-48) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 12:01:49,951 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-50) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 13:01:49,956 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-75) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 14:01:49,961 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-77) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 15:01:49,966 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-18) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 16:01:49,973 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-56) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 17:01:49,978 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-33) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 18:01:49,984 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-89) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 19:01:49,989 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-11) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 20:01:49,994 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-3) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 21:01:49,999 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-18) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 22:01:50,005 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-45) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-27 23:01:50,009 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-96) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 00:01:50,014 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-41) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 01:01:50,020 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-74) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 02:01:50,025 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-99) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 03:01:50,029 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-86) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 03:35:35,010 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (QuartzScheduler_Worker-4) AuditLogCleanupManager::deleteAgedOutAuditLogs - entered
>2013-02-28 04:01:50,035 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-19) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 05:01:50,043 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-2) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 06:01:50,049 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-72) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 07:01:50,054 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-85) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 08:01:50,059 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-55) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 09:01:50,064 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-30) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 09:50:54,503 INFO  [org.ovirt.engine.core.bll.LoginAdminUserCommand] (http-0.0.0.0-8443-1) Running command: LoginAdminUserCommand internal: false.
>2013-02-28 09:50:54,549 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,557 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,573 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,585 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,594 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,602 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,607 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-5) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,611 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,616 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-5) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,621 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,625 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-5) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,632 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,636 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-5) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,646 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,653 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-5) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,658 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,664 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-5) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,668 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,674 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-5) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,683 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-5) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,691 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-5) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,699 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:50:54,708 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:53:25,546 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-5) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:53:25,793 INFO  [org.ovirt.engine.core.bll.storage.AddEmptyStoragePoolCommand] (http-0.0.0.0-8443-5) Running command: AddEmptyStoragePoolCommand internal: false. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2013-02-28 09:53:26,971 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-5) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:53:26,980 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-5) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 09:53:30,518 INFO  [org.ovirt.engine.core.bll.AddVdsGroupCommand] (http-0.0.0.0-8443-5) Running command: AddVdsGroupCommand internal: false. Entities affected :  ID: 32d0d070-ad0c-4383-8f69-ea2a806a8eed Type: StoragePool
>2013-02-28 10:01:50,069 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-1) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 10:08:44,268 INFO  [org.ovirt.engine.core.bll.RegisterVdsQuery] (http-0.0.0.0-8443-2) Running Command: RegisterVds
>2013-02-28 10:08:44,274 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http-0.0.0.0-8443-2) Adding new value to configuration cache.
>2013-02-28 10:08:44,274 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http-0.0.0.0-8443-2) Didnt find the value of LogVdsRegistration in DB for version general - using default: false
>2013-02-28 10:08:44,473 INFO  [org.ovirt.engine.core.bll.AddVdsCommand] (pool-19-thread-49) Running command: AddVdsCommand internal: true. Entities affected :  ID: 99408929-82cf-4dc7-a532-9d998063fa95 Type: VdsGroups
>2013-02-28 10:08:44,673 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (pool-19-thread-49) Running command: AddVdsSpmIdCommand internal: true. Entities affected :  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 10:08:44,701 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-19-thread-49) No string for UNASSIGNED type. Use default Log
>2013-02-28 10:08:44,724 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (pool-19-thread-49) Cannot get vdsManager for vdsid=c78adde2-814b-11e2-9202-001a4a420b20
>2013-02-28 10:08:44,725 INFO  [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (pool-19-thread-49) START, RemoveVdsVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20), log id: 7f332e2c
>2013-02-28 10:08:44,725 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (pool-19-thread-49) Cannot get vdsManager for vdsid=c78adde2-814b-11e2-9202-001a4a420b20
>2013-02-28 10:08:44,725 INFO  [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (pool-19-thread-49) FINISH, RemoveVdsVDSCommand, log id: 7f332e2c
>2013-02-28 10:08:44,729 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (pool-19-thread-49) Cannot get vdsManager for vdsid=c78adde2-814b-11e2-9202-001a4a420b20
>2013-02-28 10:08:44,729 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (pool-19-thread-49) START, AddVdsVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20), log id: 1c02be9a
>2013-02-28 10:08:44,729 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (pool-19-thread-49) AddVds - entered , starting logic to add VDS c78adde2-814b-11e2-9202-001a4a420b20
>2013-02-28 10:08:44,734 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (pool-19-thread-49) AddVds - VDS c78adde2-814b-11e2-9202-001a4a420b20 was added, will try to add it to the resource manager
>2013-02-28 10:08:44,743 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (pool-19-thread-49) Eneterd VdsManager:constructor
>2013-02-28 10:08:44,744 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (pool-19-thread-49) vdsBroker(10.66.72.21,54,321)
>2013-02-28 10:08:45,016 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (pool-19-thread-49) ResourceManager::AddVds - VDS c78adde2-814b-11e2-9202-001a4a420b20 was added to the Resource Manager
>2013-02-28 10:08:45,017 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (pool-19-thread-49) FINISH, AddVdsVDSCommand, log id: 1c02be9a
>2013-02-28 10:08:45,049 INFO  [org.ovirt.engine.core.bll.RegisterVdsQuery] (http-0.0.0.0-8443-2) RegisterVdsQuery::Register - Registered a new VDS pending approval - Name: dell-pet105-01.qe.lab.eng.nay.redhat.com, Hostname: 10.66.72.21, UniqueID: 44454C4C-3900-104A-8048-B8C04F4D3258_00:22:19:27:54:c7
>2013-02-28 10:08:45,083 INFO  [org.ovirt.engine.core.register.RegisterServlet] (http-0.0.0.0-8443-2) Succeeded to run RegisterVds.
>2013-02-28 10:11:53,465 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:11:54,223 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:11:54,225 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:11:54,243 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:11:54,258 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:11:54,269 ERROR [org.ovirt.engine.core.bll.GetoVirtISOsQuery] (http-0.0.0.0-8443-1) ovirt ISOs directory not found. Search in: /usr/share/rhev-hypervisor
>2013-02-28 10:11:54,291 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:11:58,340 INFO  [org.ovirt.engine.core.bll.ChangeVDSClusterCommand] (http-0.0.0.0-8443-1) Running command: ChangeVDSClusterCommand internal: false. Entities affected :  ID: a6c82f58-8149-11e2-92b2-001a4a420b20 Type: VdsGroups,  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 10:11:58,445 INFO  [org.ovirt.engine.core.bll.RemoveVdsSpmIdCommand] (http-0.0.0.0-8443-1) Running command: RemoveVdsSpmIdCommand internal: true. Entities affected :  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 10:11:58,474 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (http-0.0.0.0-8443-1) Running command: AddVdsSpmIdCommand internal: true. Entities affected :  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 10:11:58,488 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http-0.0.0.0-8443-1) No string for UNASSIGNED type. Use default Log
>2013-02-28 10:11:58,625 INFO  [org.ovirt.engine.core.bll.UpdateVdsCommand] (http-0.0.0.0-8443-1) Running command: UpdateVdsCommand internal: false. Entities affected :  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 10:11:58,761 INFO  [org.ovirt.engine.core.bll.ApproveVdsCommand] (pool-19-thread-49) Running command: ApproveVdsCommand internal: false. Entities affected :  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 10:11:58,762 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-19-thread-49) Before Installation pool-19-thread-49, Powerclient/oVirtNode case: setting status to installing
>2013-02-28 10:11:58,785 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-49) START, SetVdsStatusVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, status=Installing, nonOperationalReason=NONE), log id: 714136e2
>2013-02-28 10:11:58,815 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-49) FINISH, SetVdsStatusVDSCommand, log id: 714136e2
>2013-02-28 10:11:59,346 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-19-thread-49) Before Installation pool-19-thread-49
>2013-02-28 10:11:59,348 INFO  [org.ovirt.engine.core.bll.CBCInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Executing oVirt installation stage. (Stage: Starting Host installation)
>2013-02-28 10:11:59,350 INFO  [org.ovirt.engine.core.bll.CBCInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Executing oVirt installation stage. (Stage: Connecting to Host)
>2013-02-28 10:11:59,467 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-8) Initializing Host: dell-pet105-01.qe.lab.eng.nay.redhat.com
>2013-02-28 10:12:00,334 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Recieved message: <BSTRAP component='RHEV_INSTALL' status='OK' message='Connected to Host 10.66.72.21 with SSH key fingerprint: 30:7c:5e:50:9d:e0:2e:f8:e2:4e:88:0b:81:e3:3f:16'/>. FYI. (Stage: Connecting to Host)
>2013-02-28 10:12:00,367 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Successfully connected to server ssh. (Stage: Connecting to Host)
>2013-02-28 10:12:00,368 INFO  [org.ovirt.engine.core.bll.CBCInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Executing oVirt installation stage. (Stage: Running first installation script on Host)
>2013-02-28 10:12:00,368 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) Invoking /usr/share/vdsm-reg/vdsm-gen-cert -O "rhevhhh" 10.66.72.21 4f6e3cea-fc1d-4a85-8e13-37793f523327 on 10.66.72.21
>2013-02-28 10:12:00,519 ERROR [org.ovirt.engine.core.bll.GetoVirtISOsQuery] (http-0.0.0.0-8443-1) ovirt ISOs directory not found. Search in: /usr/share/rhev-hypervisor
>2013-02-28 10:12:02,276 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Recieved message: <BSTRAP component='Encryption setup' status='OK'/>
><BSTRAP component='RHEV_INSTALL' status='OK'/>
>. Stage completed. (Stage: Running first installation script on Host)
>2013-02-28 10:12:02,303 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) RunSSHCommand returns true
>2013-02-28 10:12:02,303 INFO  [org.ovirt.engine.core.bll.CBCInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Executing oVirt installation stage. (Stage: Downloading certificate request from Host)
>2013-02-28 10:12:02,303 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) Downloading file /tmp/cert_4f6e3cea-fc1d-4a85-8e13-37793f523327.req from 10.66.72.21 to /etc/pki/rhevm/requests/cert_4f6e3cea-fc1d-4a85-8e13-37793f523327.req
>2013-02-28 10:12:03,072 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49) Installation of 10.66.72.21. successfully done sftp operation ( Stage: Downloading certificate request from Host)
>2013-02-28 10:12:03,073 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) return true
>2013-02-28 10:12:03,073 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49)  DownloadCertificateRequest ended:true
>2013-02-28 10:12:03,073 INFO  [org.ovirt.engine.core.bll.CBCInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Executing oVirt installation stage. (Stage: Sign certificate request and generate certificate)
>2013-02-28 10:12:04,090 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49)  SignCertificateRequest ended:true
>2013-02-28 10:12:04,090 INFO  [org.ovirt.engine.core.bll.CBCInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Executing oVirt installation stage. (Stage: Upload signed sertificate to Host)
>2013-02-28 10:12:04,091 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) Uploading file /etc/pki/rhevm/certs/10.66.72.21cert.pem to /tmp/cert_4f6e3cea-fc1d-4a85-8e13-37793f523327.pem on 10.66.72.21
>2013-02-28 10:12:04,091 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) Uploading file /etc/pki/rhevm/certs/10.66.72.21cert.pem to /tmp/cert_4f6e3cea-fc1d-4a85-8e13-37793f523327.pem on 10.66.72.21
>2013-02-28 10:12:04,347 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49) Installation of 10.66.72.21. successfully done sftp operation ( Stage: Upload signed sertificate to Host)
>2013-02-28 10:12:04,347 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) return true
>2013-02-28 10:12:04,347 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49)  UploadSignedCertificate ended:true
>2013-02-28 10:12:04,348 INFO  [org.ovirt.engine.core.bll.CBCInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Executing oVirt installation stage. (Stage: Upload Cerficate Autority to Host)
>2013-02-28 10:12:04,348 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) Uploading file /etc/pki/rhevm/ca.pem to /tmp/CA_4f6e3cea-fc1d-4a85-8e13-37793f523327.pem on 10.66.72.21
>2013-02-28 10:12:04,348 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) Uploading file /etc/pki/rhevm/ca.pem to /tmp/CA_4f6e3cea-fc1d-4a85-8e13-37793f523327.pem on 10.66.72.21
>2013-02-28 10:12:04,561 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49) Installation of 10.66.72.21. successfully done sftp operation ( Stage: Upload Cerficate Autority to Host)
>2013-02-28 10:12:04,561 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) return true
>2013-02-28 10:12:04,562 INFO  [org.ovirt.engine.core.bll.CBCInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Executing oVirt installation stage, sending SSH Command /usr/share/vdsm-reg/vdsm-complete -c 'ssl=true' 4f6e3cea-fc1d-4a85-8e13-37793f523327 0. (Stage: Running second installation script on Host)
>2013-02-28 10:12:04,562 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) Invoking /usr/share/vdsm-reg/vdsm-complete -c 'ssl=true' 4f6e3cea-fc1d-4a85-8e13-37793f523327 0 on 10.66.72.21
>2013-02-28 10:12:20,164 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Recieved message: <BSTRAP component='instCert' status='OK'/>
><BSTRAP component='CoreDump' status='OK'/>
><BSTRAP component='cleanAll' status='OK'/>
><BSTRAP component='VDS Configuration' status='OK'/>
><BSTRAP component='Restart' status='OK' message='Restarting vdsmd service' />
><BSTRAP component='RHEV_INSTALL' status='OK'/>
>. Stage completed. (Stage: Running second installation script on Host)
>2013-02-28 10:12:20,295 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) RunSSHCommand returns true
>2013-02-28 10:12:20,315 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-19-thread-49) After Installation pool-19-thread-49
>2013-02-28 10:12:20,317 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-49) START, SetVdsStatusVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, status=Reboot, nonOperationalReason=NONE), log id: 56b5ec1b
>2013-02-28 10:12:20,337 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-49) FINISH, SetVdsStatusVDSCommand, log id: 56b5ec1b
>2013-02-28 10:12:20,343 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-50) START, SetVdsStatusVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, status=NonResponsive, nonOperationalReason=NONE), log id: 22d68530
>2013-02-28 10:12:20,345 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-49) START, SetVdsStatusVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, status=Unassigned, nonOperationalReason=NONE), log id: 78f60751
>2013-02-28 10:12:20,368 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-50) FINISH, SetVdsStatusVDSCommand, log id: 22d68530
>2013-02-28 10:12:20,379 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-49) FINISH, SetVdsStatusVDSCommand, log id: 78f60751
>2013-02-28 10:12:20,874 ERROR [org.ovirt.engine.core.bll.GetoVirtISOsQuery] (http-0.0.0.0-8443-2) ovirt ISOs directory not found. Search in: /usr/share/rhev-hypervisor
>2013-02-28 10:12:22,245 WARN  [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-53) ResourceManager::refreshVdsRunTimeInfo::Failed to refresh VDS , vds = c78adde2-814b-11e2-9202-001a4a420b20 : dell-pet105-01.qe.lab.eng.nay.redhat.com, VDS Network Error, continuing.
>VDSNetworkException: 
>2013-02-28 10:12:22,964 ERROR [org.ovirt.engine.core.bll.GetoVirtISOsQuery] (http-0.0.0.0-8443-2) ovirt ISOs directory not found. Search in: /usr/share/rhev-hypervisor
>2013-02-28 10:12:24,629 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http-0.0.0.0-8443-2) Adding new value to configuration cache.
>2013-02-28 10:12:24,629 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http-0.0.0.0-8443-2) Didnt find the value of DBI18NPrefix in DB for version general - using default: 
>2013-02-28 10:12:25,269 INFO  [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (QuartzScheduler_Worker-6) Running command: InitVdsOnUpCommand internal: true.
>2013-02-28 10:12:25,297 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (QuartzScheduler_Worker-6) No string for UNASSIGNED type. Use default Log
>2013-02-28 10:12:25,338 INFO  [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-19-thread-50) MultipleActionsRunner of type MigrateVmToServer invoked with no actions
>2013-02-28 10:12:25,387 INFO  [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (QuartzScheduler_Worker-6) Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected :  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 10:12:25,479 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (QuartzScheduler_Worker-6) Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 10:12:33,757 INFO  [org.ovirt.engine.core.bll.MaintananceNumberOfVdssCommand] (pool-19-thread-50) Running command: MaintananceNumberOfVdssCommand internal: false. Entities affected :  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 10:12:33,760 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-50) START, SetVdsStatusVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, status=PreparingForMaintenance, nonOperationalReason=NONE), log id: 7ccd8aa5
>2013-02-28 10:12:33,786 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-50) FINISH, SetVdsStatusVDSCommand, log id: 7ccd8aa5
>2013-02-28 10:12:33,867 INFO  [org.ovirt.engine.core.bll.MaintananceVdsCommand] (pool-19-thread-50) Running command: MaintananceVdsCommand internal: true. Entities affected :  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 10:12:35,324 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-55) vds::Updated vds status from Preparing for Maintenance to Maintenance in database,  vds = c78adde2-814b-11e2-9202-001a4a420b20 : dell-pet105-01.qe.lab.eng.nay.redhat.com
>2013-02-28 10:23:08,032 INFO  [org.ovirt.engine.core.bll.UpdateVdsCommand] (http-0.0.0.0-8443-2) Running command: UpdateVdsCommand internal: false. Entities affected :  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 10:23:08,057 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (http-0.0.0.0-8443-2) START, SetVdsStatusVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, status=Installing, nonOperationalReason=NONE), log id: 73734dd9
>2013-02-28 10:23:08,066 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (http-0.0.0.0-8443-2) FINISH, SetVdsStatusVDSCommand, log id: 73734dd9
>2013-02-28 10:23:08,098 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-19-thread-49) Running command: InstallVdsCommand internal: true. Entities affected :  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 10:23:08,098 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-19-thread-49) Before Installation pool-19-thread-49, Powerclient/oVirtNode case: setting status to installing
>2013-02-28 10:23:08,098 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-49) START, SetVdsStatusVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, status=Installing, nonOperationalReason=NONE), log id: 20d580fb
>2013-02-28 10:23:08,127 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-49) FINISH, SetVdsStatusVDSCommand, log id: 20d580fb
>2013-02-28 10:23:08,134 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-19-thread-49) Before Installation pool-19-thread-49
>2013-02-28 10:23:08,136 INFO  [org.ovirt.engine.core.bll.OVirtInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Executing oVirt reinstall/upgrade stage. (Stage: Starting Host installation)
>2013-02-28 10:23:08,136 INFO  [org.ovirt.engine.core.bll.OVirtInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Executing oVirt reinstall/upgrade stage. (Stage: Connecting to Host)
>2013-02-28 10:23:08,778 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Recieved message: <BSTRAP component='RHEV_INSTALL' status='OK' message='Connected to Host 10.66.72.21 with SSH key fingerprint: 30:7c:5e:50:9d:e0:2e:f8:e2:4e:88:0b:81:e3:3f:16'/>. FYI. (Stage: Connecting to Host)
>2013-02-28 10:23:08,789 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Successfully connected to server ssh. (Stage: Connecting to Host)
>2013-02-28 10:23:08,790 INFO  [org.ovirt.engine.core.bll.OVirtInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Executing oVirt reinstall/upgrade stage. (Stage: Upload upgrade ISO to oVirt Node)
>2013-02-28 10:23:08,790 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) Uploading file /usr/share/rhev-hypervisor/rhevh-6.4-20130227.0.el6_4.iso to /data/updates/ovirt-node-image.iso on 10.66.72.21
>2013-02-28 10:23:08,790 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Recieved message: Preparing ISO file. FYI. (Stage: Upload upgrade ISO to oVirt Node)
>2013-02-28 10:23:08,790 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (pool-19-thread-49) VDS message: Preparing ISO file
>2013-02-28 10:23:09,160 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Recieved message: Determining current address from host perspective. FYI. (Stage: Upload upgrade ISO to oVirt Node)
>2013-02-28 10:23:09,160 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (pool-19-thread-49) VDS message: Determining current address from host perspective
>2013-02-28 10:23:09,160 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) getLocalIP entry
>2013-02-28 10:23:09,209 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) getLocalIP return: 10.66.104.178
>
>2013-02-28 10:23:09,209 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Recieved message: Starting ISO upload. FYI. (Stage: Upload upgrade ISO to oVirt Node)
>2013-02-28 10:23:09,209 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (pool-19-thread-49) VDS message: Starting ISO upload
>2013-02-28 10:23:12,674 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Recieved message: ISO upload ended successfully. FYI. (Stage: Upload upgrade ISO to oVirt Node)
>2013-02-28 10:23:12,674 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (pool-19-thread-49) VDS message: ISO upload ended successfully
>2013-02-28 10:23:12,674 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49) Installation of 10.66.72.21. successfully done sftp operation ( Stage: Upload upgrade ISO to oVirt Node)
>2013-02-28 10:23:12,842 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) uploadLargeFile returns true
>2013-02-28 10:23:12,842 INFO  [org.ovirt.engine.core.bll.OVirtInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Executing oVirt reinstall/upgrade stage. (Stage: Running upgrade / reinstall script on oVirt Node)
>2013-02-28 10:23:12,842 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) Invoking /usr/share/vdsm-reg/vdsm-upgrade on 10.66.72.21
>2013-02-28 10:23:32,357 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-49) Installation of 10.66.72.21. Recieved message: <BSTRAP component='RHEL_INSTALL' status='OK'message='vdsm daemon stopped for upgrade process!'/>
><BSTRAP component='setMountPoint' status='OK' message='Mount succeeded.'/>
><BSTRAP component='doUpgrade' status='OK' message='Upgrade Succeeded. Rebooting'/>
><BSTRAP component='umount' status='OK' message='umount Succeeded'/>
><BSTRAP component='RHEV_INSTALL' status='OK'/>
>. Stage completed. (Stage: Running upgrade / reinstall script on oVirt Node)
>2013-02-28 10:23:32,364 ERROR [org.ovirt.engine.core.bll.InstallerMessages] (pool-19-thread-49) Installation of Host. Received illegal XML from Host. Message: org.ovirt.engine.core.compat.CompatException: Element type "BSTRAP" must be followed by either attribute specifications, ">" or "/>"., Exception: {2}
>2013-02-28 10:23:32,409 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-49) RunSSHCommand returns true
>2013-02-28 10:23:32,411 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-19-thread-49) After Installation pool-19-thread-49
>2013-02-28 10:23:32,413 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-49) START, SetVdsStatusVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, status=Reboot, nonOperationalReason=NONE), log id: 168ddfa6
>2013-02-28 10:23:32,431 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-49) FINISH, SetVdsStatusVDSCommand, log id: 168ddfa6
>2013-02-28 10:23:32,435 INFO  [org.ovirt.engine.core.bll.VdsCommand] (pool-19-thread-50) Waiting 300 seconds, for server to finish reboot process.
>2013-02-28 10:26:43,432 ERROR [org.ovirt.engine.core.bll.RegisterVdsQuery] (http-0.0.0.0-8443-2) RegisterVdsQuery::ExecuteQueryCommand: CanDoAction failed: VDS_STATUS_NOT_VALID_FOR_UPDATE
>2013-02-28 10:26:43,433 ERROR [org.ovirt.engine.core.bll.RegisterVdsQuery] (http-0.0.0.0-8443-2) Query RegisterVdsQuery failed. Exception message is VDS_STATUS_NOT_VALID_FOR_UPDATE
>2013-02-28 10:26:43,434 INFO  [org.ovirt.engine.core.register.RegisterServlet] (http-0.0.0.0-8443-2) Succeeded to run RegisterVds.
>2013-02-28 10:28:32,452 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-50) START, SetVdsStatusVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, status=NonResponsive, nonOperationalReason=NONE), log id: 2468d2b
>2013-02-28 10:28:32,470 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-50) FINISH, SetVdsStatusVDSCommand, log id: 2468d2b
>2013-02-28 10:28:34,100 INFO  [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (QuartzScheduler_Worker-32) Running command: InitVdsOnUpCommand internal: true.
>2013-02-28 10:28:34,120 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (QuartzScheduler_Worker-32) No string for UNASSIGNED type. Use default Log
>2013-02-28 10:28:34,123 INFO  [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-19-thread-50) MultipleActionsRunner of type MigrateVmToServer invoked with no actions
>2013-02-28 10:28:34,145 INFO  [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (QuartzScheduler_Worker-32) Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected :  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 10:28:34,165 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (QuartzScheduler_Worker-32) Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 10:41:34,092 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:41:34,142 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetDeviceListVDSCommand] (http-0.0.0.0-8443-1) START, GetDeviceListVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, storageType=FCP), log id: 1cd6a083
>2013-02-28 10:41:37,062 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetDeviceListVDSCommand] (http-0.0.0.0-8443-1) FINISH, GetDeviceListVDSCommand, return: [org.ovirt.engine.core.common.businessentities.LUNs@cf5196b6, org.ovirt.engine.core.common.businessentities.LUNs@bb87bacb], log id: 1cd6a083
>2013-02-28 10:43:09,379 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:43:09,621 INFO  [org.ovirt.engine.core.bll.storage.AddSANStorageDomainCommand] (http-0.0.0.0-8443-1) Running command: AddSANStorageDomainCommand internal: false. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2013-02-28 10:43:09,697 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVGVDSCommand] (http-0.0.0.0-8443-1) START, CreateVGVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, storageDomainId=c6c77717-201d-47fc-aff3-023806e79ac5, deviceList=[3600a0b80005adb0b00004d834f0b7954]), log id: 25dfb408
>2013-02-28 10:43:11,388 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVGVDSCommand] (http-0.0.0.0-8443-1) FINISH, CreateVGVDSCommand, return: gF89u0-W59W-K2JD-qGrk-Pfzb-l1hW-xG74VM, log id: 25dfb408
>2013-02-28 10:43:11,397 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (http-0.0.0.0-8443-1) START, CreateStorageDomainVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, storageDomain=org.ovirt.engine.core.common.businessentities.storage_domain_static@25a41e88, args=gF89u0-W59W-K2JD-qGrk-Pfzb-l1hW-xG74VM), log id: 56ab9e8
>2013-02-28 10:43:19,617 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (http-0.0.0.0-8443-1) FINISH, CreateStorageDomainVDSCommand, log id: 56ab9e8
>2013-02-28 10:43:19,635 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (http-0.0.0.0-8443-1) START, GetStorageDomainStatsVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, storageDomainId=c6c77717-201d-47fc-aff3-023806e79ac5), log id: 32ed0551
>2013-02-28 10:43:20,034 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (http-0.0.0.0-8443-1) FINISH, GetStorageDomainStatsVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage_domains@1243246f, log id: 32ed0551
>2013-02-28 10:43:20,068 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVGInfoVDSCommand] (http-0.0.0.0-8443-1) START, GetVGInfoVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, VGID=gF89u0-W59W-K2JD-qGrk-Pfzb-l1hW-xG74VM), log id: 3415b1b5
>2013-02-28 10:43:20,117 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVGInfoVDSCommand] (http-0.0.0.0-8443-1) FINISH, GetVGInfoVDSCommand, return: [org.ovirt.engine.core.common.businessentities.LUNs@81bd230b], log id: 3415b1b5
>2013-02-28 10:43:20,328 INFO  [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (pool-19-thread-50) Lock Aquired to object 32d0d070-ad0c-4383-8f69-ea2a806a8eed
>2013-02-28 10:43:20,328 INFO  [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (pool-19-thread-50) Running command: AddStoragePoolWithStoragesCommand internal: false. Entities affected :  ID: 32d0d070-ad0c-4383-8f69-ea2a806a8eed Type: StoragePool
>2013-02-28 10:43:20,527 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStoragePoolVDSCommand] (pool-19-thread-50) START, CreateStoragePoolVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, storagePoolId=32d0d070-ad0c-4383-8f69-ea2a806a8eed, storageType=FCP, storagePoolName=fc, masterDomainId=c6c77717-201d-47fc-aff3-023806e79ac5, domainsIdList=[c6c77717-201d-47fc-aff3-023806e79ac5], masterVersion=1), log id: 33265a35
>2013-02-28 10:43:46,828 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStoragePoolVDSCommand] (pool-19-thread-50) FINISH, CreateStoragePoolVDSCommand, log id: 33265a35
>2013-02-28 10:43:46,889 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-19-thread-50) Lock Aquired to object c6c77717-201d-47fc-aff3-023806e79ac5
>2013-02-28 10:43:46,890 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-19-thread-50) Running command: ActivateStorageDomainCommand internal: true. Entities affected :  ID: c6c77717-201d-47fc-aff3-023806e79ac5 Type: Storage
>2013-02-28 10:43:46,914 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-19-thread-50) ActivateStorage Domain. Before Connect all hosts to pool. Time:2/28/13 10:43 AM
>2013-02-28 10:43:46,989 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-19-thread-50) ActivateStorage Domain. After Connect all hosts to pool. Time:2/28/13 10:43 AM
>2013-02-28 10:43:46,994 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (pool-19-thread-50) START, ActivateStorageDomainVDSCommand(storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = c6c77717-201d-47fc-aff3-023806e79ac5), log id: 4fb2ba90
>2013-02-28 10:43:47,047 INFO  [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (pool-19-thread-50) Running command: SetStoragePoolStatusCommand internal: true. Entities affected :  ID: 32d0d070-ad0c-4383-8f69-ea2a806a8eed Type: StoragePool
>2013-02-28 10:43:47,148 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (pool-19-thread-50) hostFromVds::selectedVds - dell-pet105-01.qe.lab.eng.nay.redhat.com, spmStatus Unknown_Pool, storage pool fc
>2013-02-28 10:43:47,165 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (pool-19-thread-50) START, ConnectStoragePoolVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, vds_spm_id = 1, masterDomainId = c6c77717-201d-47fc-aff3-023806e79ac5, masterVersion = 1), log id: 785fe334
>2013-02-28 10:43:52,285 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (pool-19-thread-50) FINISH, ConnectStoragePoolVDSCommand, log id: 785fe334
>2013-02-28 10:43:52,304 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (pool-19-thread-50) hostFromVds::Connected host to pool - selectedVds - dell-pet105-01.qe.lab.eng.nay.redhat.com, spmStatus Free, storage pool fc
>2013-02-28 10:43:52,322 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (pool-19-thread-50) starting spm on vds dell-pet105-01.qe.lab.eng.nay.redhat.com, storage pool fc, prevId -1, LVER -1
>2013-02-28 10:43:52,330 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (pool-19-thread-50) START, SpmStartVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, prevId=-1, prevLVER=-1, storagePoolFormatType=V2, recoveryMode=Manual, SCSIFencing=false), log id: 23086074
>2013-02-28 10:43:52,356 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (pool-19-thread-50) spmStart polling started: taskId = e0dbb96a-6921-4573-a6bc-f0676552e4b4
>2013-02-28 10:44:15,911 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (pool-19-thread-50) spmStart polling ended: taskId = e0dbb96a-6921-4573-a6bc-f0676552e4b4 task status = finished
>2013-02-28 10:44:15,929 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (pool-19-thread-50) spmStart polling ended. spm status: SPM
>2013-02-28 10:44:15,945 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-19-thread-50) START, HSMClearTaskVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, taskId=e0dbb96a-6921-4573-a6bc-f0676552e4b4), log id: 4c071488
>2013-02-28 10:44:15,964 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-19-thread-50) FINISH, HSMClearTaskVDSCommand, log id: 4c071488
>2013-02-28 10:44:15,964 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (pool-19-thread-50) FINISH, SpmStartVDSCommand, return: org.ovirt.engine.core.common.businessentities.SpmStatusResult@45267cda, log id: 23086074
>2013-02-28 10:44:15,966 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (pool-19-thread-50) ResourceManager:StoragePoolStatusChange - no event listener defined, nothing done.
>2013-02-28 10:44:15,966 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (pool-19-thread-50) Initialize Irs proxy from vds: 10.66.72.21
>2013-02-28 10:44:16,042 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (pool-19-thread-50) FINISH, ActivateStorageDomainVDSCommand, log id: 4fb2ba90
>2013-02-28 10:44:16,043 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-19-thread-50) ActivateStorage Domain. After Activate storage domain in vds. Time:2/28/13 10:44 AM
>2013-02-28 10:44:16,092 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (pool-19-thread-48) START, ConnectStoragePoolVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, vds_spm_id = 1, masterDomainId = c6c77717-201d-47fc-aff3-023806e79ac5, masterVersion = 1), log id: d97c7a3
>2013-02-28 10:44:16,111 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (pool-19-thread-48) FINISH, ConnectStoragePoolVDSCommand, log id: d97c7a3
>2013-02-28 10:44:16,111 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-19-thread-50) ActivateStorage Domain. After Refresh all pools . Time:2/28/13 10:44 AM
>2013-02-28 10:44:16,130 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-19-thread-50) ActivateStorage Domain. After change storage pool status in vds. Time:2/28/13 10:44 AM
>2013-02-28 10:44:16,133 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-19-thread-50) Lock freed to object c6c77717-201d-47fc-aff3-023806e79ac5
>2013-02-28 10:44:16,138 INFO  [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (pool-19-thread-50) Lock freed to object 32d0d070-ad0c-4383-8f69-ea2a806a8eed
>2013-02-28 10:48:56,069 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:48:56,071 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:48:56,393 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:48:56,401 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:49:09,672 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (http-0.0.0.0-8443-1) START, IsValidVDSCommand(storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, ignoreFailoverLimit = false, compatabilityVersion = null), log id: f2152e1
>2013-02-28 10:49:09,673 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (http-0.0.0.0-8443-1) FINISH, IsValidVDSCommand, return: true, log id: f2152e1
>2013-02-28 10:49:09,691 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (http-0.0.0.0-8443-1) Running command: AddVmFromScratchCommand internal: false. Entities affected :  ID: a6c82f58-8149-11e2-92b2-001a4a420b20 Type: VdsGroups
>2013-02-28 10:49:09,694 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (http-0.0.0.0-8443-1) START, IsValidVDSCommand(storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 796241a4
>2013-02-28 10:49:09,695 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (http-0.0.0.0-8443-1) FINISH, IsValidVDSCommand, return: true, log id: 796241a4
>2013-02-28 10:49:10,052 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (http-0.0.0.0-8443-1) START, UpdateVMVDSCommand(storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 00000000-0000-0000-0000-000000000000, infoDictionary.size = 1), log id: 4726915e
>2013-02-28 10:49:10,092 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (http-0.0.0.0-8443-1) FINISH, UpdateVMVDSCommand, log id: 4726915e
>2013-02-28 10:49:13,042 INFO  [org.ovirt.engine.core.bll.AddVmInterfaceCommand] (http-0.0.0.0-8443-1) Running command: AddVmInterfaceCommand internal: false. Entities affected :  ID: a4554520-43da-4575-9c6e-59c0feecdc02 Type: VM
>2013-02-28 10:49:13,045 INFO  [org.ovirt.engine.core.bll.MacPoolManager] (http-0.0.0.0-8443-1) MacPoolManager::allocateNewMac entered
>2013-02-28 10:49:13,045 INFO  [org.ovirt.engine.core.bll.MacPoolManager] (http-0.0.0.0-8443-1) MacPoolManager::allocateNewMac allocated mac = 00:1a:4a:42:68:00
>2013-02-28 10:49:13,841 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:49:19,501 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:49:19,699 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (http-0.0.0.0-8443-1) START, IsValidVDSCommand(storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 253158e4
>2013-02-28 10:49:19,700 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (http-0.0.0.0-8443-1) FINISH, IsValidVDSCommand, return: true, log id: 253158e4
>2013-02-28 10:49:19,736 INFO  [org.ovirt.engine.core.bll.AddDiskToVmCommand] (http-0.0.0.0-8443-1) Running command: AddDiskToVmCommand internal: false. Entities affected :  ID: a4554520-43da-4575-9c6e-59c0feecdc02 Type: VM
>2013-02-28 10:49:19,786 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (http-0.0.0.0-8443-1) START, SetVmStatusVDSCommand(vmId = a4554520-43da-4575-9c6e-59c0feecdc02, status = ImageLocked), log id: 333b3088
>2013-02-28 10:49:19,863 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (http-0.0.0.0-8443-1) FINISH, SetVmStatusVDSCommand, log id: 333b3088
>2013-02-28 10:49:19,951 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (http-0.0.0.0-8443-1) Running command: AddImageFromScratchCommand internal: true. Entities affected :  ID: c6c77717-201d-47fc-aff3-023806e79ac5 Type: Storage
>2013-02-28 10:49:19,996 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (http-0.0.0.0-8443-1) START, CreateImageVDSCommand(storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, ignoreFailoverLimit = false, compatabilityVersion = 3.0, storageDomainId = c6c77717-201d-47fc-aff3-023806e79ac5, imageGroupId = 788f9532-0e38-491e-b842-ddc095a0aa16, imageSizeInBytes = 8589934592, volumeFormat = COW, diskType = System, newImageId = a84fd6f7-4229-42f2-829e-074753442325, newImageDescription = _ActiveImage_fc1_Thu Feb 28 10:49:19 CST 2013), log id: 4eddfa4
>2013-02-28 10:49:19,996 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (http-0.0.0.0-8443-1) -- CreateImageVDSCommand::ExecuteIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID
>2013-02-28 10:49:19,997 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (http-0.0.0.0-8443-1) -- createVolume parameters:
>                sdUUID=c6c77717-201d-47fc-aff3-023806e79ac5
>                spUUID=32d0d070-ad0c-4383-8f69-ea2a806a8eed
>                imgGUID=788f9532-0e38-491e-b842-ddc095a0aa16
>                size=8,589,934,592 bytes
>                volFormat=COW
>                volType=Sparse
>                diskType=System
>                volUUID=a84fd6f7-4229-42f2-829e-074753442325
>                descr=_ActiveImage_fc1_Thu Feb 28 10:49:19 CST 2013
>                srcImgGUID=00000000-0000-0000-0000-000000000000
>                srcVolUUID=00000000-0000-0000-0000-000000000000
>                
>2013-02-28 10:49:20,904 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (http-0.0.0.0-8443-1) FINISH, CreateImageVDSCommand, return: a84fd6f7-4229-42f2-829e-074753442325, log id: 4eddfa4
>2013-02-28 10:49:20,920 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (http-0.0.0.0-8443-1) BaseAsyncTask::AddOrUpdateTaskInDB: Adding task 3d04ed05-3856-422a-a84b-c0ff4c840f10 to DataBase
>2013-02-28 10:49:20,943 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (http-0.0.0.0-8443-1) EntityAsyncTask::Adding EntityMultiAsyncTasks object for entity a4554520-43da-4575-9c6e-59c0feecdc02
>2013-02-28 10:49:20,945 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (http-0.0.0.0-8443-1) EntityMultiAsyncTasks::AttachTask: Attaching task 3d04ed05-3856-422a-a84b-c0ff4c840f10 to entity a4554520-43da-4575-9c6e-59c0feecdc02.
>2013-02-28 10:49:20,945 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (http-0.0.0.0-8443-1) AsyncTaskManager::AddTaskToManager: Adding task 3d04ed05-3856-422a-a84b-c0ff4c840f10 (Parent Command AddDiskToVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2013-02-28 10:49:21,006 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (http-0.0.0.0-8443-1) BaseAsyncTask::StartPollingTask: Starting to poll task 3d04ed05-3856-422a-a84b-c0ff4c840f10.
>2013-02-28 10:49:21,006 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (http-0.0.0.0-8443-1) EntityMultiAsyncTasks::StartPollingTask: Current Action Type for entity a4554520-43da-4575-9c6e-59c0feecdc02 is AddDiskToVm (determined by task 3d04ed05-3856-422a-a84b-c0ff4c840f10)
>2013-02-28 10:49:25,086 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-66) AsyncTaskManager::PollAndUpdateAsyncTasks: 1 tasks, 1 tasks to poll now
>2013-02-28 10:49:25,142 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-66) SPMAsyncTask::PollTask: Polling task 3d04ed05-3856-422a-a84b-c0ff4c840f10 (Parent Command AddDiskToVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2013-02-28 10:49:25,146 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-66) BaseAsyncTask::OnTaskEndSuccess: Task 3d04ed05-3856-422a-a84b-c0ff4c840f10 (Parent Command AddDiskToVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2013-02-28 10:49:25,146 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-66) EntityAsyncTask::EndActionIfNecessary: All tasks of entity a4554520-43da-4575-9c6e-59c0feecdc02 has ended -> executing EndAction
>2013-02-28 10:49:25,147 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-66) EntityAsyncTask::EndAction: Ending action for 1 tasks (entity ID: a4554520-43da-4575-9c6e-59c0feecdc02): calling EndAction for action type AddDiskToVm.
>2013-02-28 10:49:25,153 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-19-thread-49) EntityAsyncTask::EndCommandAction [within thread]: Attempting to EndAction AddDiskToVm
>2013-02-28 10:49:25,158 INFO  [org.ovirt.engine.core.bll.AddDiskToVmCommand] (pool-19-thread-49) Ending command successfully: org.ovirt.engine.core.bll.AddDiskToVmCommand
>2013-02-28 10:49:25,159 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (pool-19-thread-49) Ending command successfully: org.ovirt.engine.core.bll.AddImageFromScratchCommand
>2013-02-28 10:49:25,188 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-19-thread-49) START, GetImageInfoVDSCommand(storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = c6c77717-201d-47fc-aff3-023806e79ac5, imageGroupId = 788f9532-0e38-491e-b842-ddc095a0aa16, imageId = a84fd6f7-4229-42f2-829e-074753442325), log id: 73f81d33
>2013-02-28 10:49:25,978 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-19-thread-49) FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@a140702, log id: 73f81d33
>2013-02-28 10:49:26,093 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-19-thread-49) START, SetVmStatusVDSCommand(vmId = a4554520-43da-4575-9c6e-59c0feecdc02, status = Down), log id: 2d06c686
>2013-02-28 10:49:26,121 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-19-thread-49) FINISH, SetVmStatusVDSCommand, log id: 2d06c686
>2013-02-28 10:49:26,198 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (pool-19-thread-49) START, UpdateVMVDSCommand(storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 00000000-0000-0000-0000-000000000000, infoDictionary.size = 1), log id: 13340e6d
>2013-02-28 10:49:26,228 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (pool-19-thread-49) FINISH, UpdateVMVDSCommand, log id: 13340e6d
>2013-02-28 10:49:26,254 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-19-thread-49) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type AddDiskToVm completed, handling the result.
>2013-02-28 10:49:26,254 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-19-thread-49) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type AddDiskToVm succeeded, clearing tasks.
>2013-02-28 10:49:26,254 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-19-thread-49) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 3d04ed05-3856-422a-a84b-c0ff4c840f10
>2013-02-28 10:49:26,258 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-19-thread-49) START, SPMClearTaskVDSCommand(storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, ignoreFailoverLimit = false, compatabilityVersion = null, taskId = 3d04ed05-3856-422a-a84b-c0ff4c840f10), log id: 6f10d867
>2013-02-28 10:49:26,262 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-19-thread-49) START, HSMClearTaskVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, taskId=3d04ed05-3856-422a-a84b-c0ff4c840f10), log id: cac0f55
>2013-02-28 10:49:26,279 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-19-thread-49) FINISH, HSMClearTaskVDSCommand, log id: cac0f55
>2013-02-28 10:49:26,279 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-19-thread-49) FINISH, SPMClearTaskVDSCommand, log id: 6f10d867
>2013-02-28 10:49:26,288 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-19-thread-49) BaseAsyncTask::RemoveTaskFromDB: Removing task 3d04ed05-3856-422a-a84b-c0ff4c840f10 from DataBase
>2013-02-28 10:49:26,298 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-19-thread-49) EntityAsyncTask::HandleEndActionResult [within thread]: Removing EntityMultiAsyncTasks object for entity a4554520-43da-4575-9c6e-59c0feecdc02
>2013-02-28 10:49:56,277 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:49:56,280 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:49:56,286 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:49:56,289 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:49:56,301 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:49:56,337 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:49:57,640 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (http-0.0.0.0-8443-2) START, IsValidVDSCommand(storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 7603a89d
>2013-02-28 10:49:57,643 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (http-0.0.0.0-8443-2) FINISH, IsValidVDSCommand, return: true, log id: 7603a89d
>2013-02-28 10:49:57,682 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http-0.0.0.0-8443-2) START, IsVmDuringInitiatingVDSCommand(vmId = a4554520-43da-4575-9c6e-59c0feecdc02), log id: 22591b0
>2013-02-28 10:49:57,683 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http-0.0.0.0-8443-2) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 22591b0
>2013-02-28 10:49:57,761 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-19-thread-47) Running command: RunVmCommand internal: false. Entities affected :  ID: a4554520-43da-4575-9c6e-59c0feecdc02 Type: VM
>2013-02-28 10:49:57,819 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-19-thread-47) START, CreateVmVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, vmId=a4554520-43da-4575-9c6e-59c0feecdc02, vm=org.ovirt.engine.core.common.businessentities.VM@628bec66), log id: 2942d47b
>2013-02-28 10:49:57,830 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-19-thread-47) START, CreateVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, vmId=a4554520-43da-4575-9c6e-59c0feecdc02, vm=org.ovirt.engine.core.common.businessentities.VM@628bec66), log id: 30487c0f
>2013-02-28 10:49:57,912 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-19-thread-47) org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand kvmEnable=true,nicModel=pv,keyboardLayout=en-us,nice=0,timeOffset=0,transparentHugePages=true,drives=[Ljava.util.Map;@6a19b8f,vmId=a4554520-43da-4575-9c6e-59c0feecdc02,acpiEnable=true,custom={},spiceSslCipherSuite=DEFAULT,memSize=666,boot=n,smp=1,emulatedMachine=rhel6.2.0,vmType=kvm,display=vnc,tabletEnable=true,spiceSecureChannels=smain,sinputs,smpCoresPerSocket=1,spiceMonitors=1,macAddr=00:1a:4a:42:68:00,bridge=rhevm,vmName=fc1,cpuType=Opteron_G2
>2013-02-28 10:49:57,913 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-19-thread-47) FINISH, CreateVDSCommand, log id: 30487c0f
>2013-02-28 10:49:57,926 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-19-thread-47) IncreasePendingVms::CreateVmIncreasing vds dell-pet105-01.qe.lab.eng.nay.redhat.com pending vcpu count, now 1. Vm: fc1
>2013-02-28 10:49:57,942 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-19-thread-47) FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 2942d47b
>2013-02-28 10:50:01,006 INFO  [org.ovirt.engine.core.bll.SetVmTicketCommand] (http-0.0.0.0-8443-1) Running command: SetVmTicketCommand internal: false. Entities affected :  ID: a4554520-43da-4575-9c6e-59c0feecdc02 Type: VM
>2013-02-28 10:50:01,020 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (http-0.0.0.0-8443-1) START, SetVmTicketVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, vmId=a4554520-43da-4575-9c6e-59c0feecdc02, ticket=HYq8mx92RXJU, validTime=120), log id: 207ed0e6
>2013-02-28 10:50:01,049 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (http-0.0.0.0-8443-1) FINISH, SetVmTicketVDSCommand, log id: 207ed0e6
>2013-02-28 10:50:45,060 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-36) AsyncTaskManager::SetNewMap: The map contains now 0 tasks
>2013-02-28 10:50:45,060 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-36) AsyncTaskManager::RemoveOldAndCleanedTasks: Cleared all tasks of pool 32d0d070-ad0c-4383-8f69-ea2a806a8eed.
>2013-02-28 10:50:45,065 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-36) AsyncTaskManager::RemoveOldAndCleanedTasks: could not find vds that is spm and non-operational.
>2013-02-28 10:50:59,426 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-30) START, UpdateVdsDynamicDataVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@507f2c84), log id: 3d4f36f7
>2013-02-28 10:50:59,433 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-30) FINISH, UpdateVdsDynamicDataVDSCommand, log id: 3d4f36f7
>2013-02-28 10:57:58,797 INFO  [org.ovirt.engine.core.bll.SetVmTicketCommand] (http-0.0.0.0-8443-2) Running command: SetVmTicketCommand internal: false. Entities affected :  ID: a4554520-43da-4575-9c6e-59c0feecdc02 Type: VM
>2013-02-28 10:57:58,806 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (http-0.0.0.0-8443-2) START, SetVmTicketVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, vmId=a4554520-43da-4575-9c6e-59c0feecdc02, ticket=9orrHQehuaBH, validTime=120), log id: 7441a095
>2013-02-28 10:57:58,837 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (http-0.0.0.0-8443-2) FINISH, SetVmTicketVDSCommand, log id: 7441a095
>2013-02-28 10:58:06,136 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (pool-19-thread-48) Running command: ShutdownVmCommand internal: false. Entities affected :  ID: a4554520-43da-4575-9c6e-59c0feecdc02 Type: VM
>2013-02-28 10:58:06,140 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (pool-19-thread-48) VmHandler.cs:ShutdownVmCommand:Perform: entered (Desktop fc1).
>2013-02-28 10:58:06,140 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (pool-19-thread-48) VmHandler.cs:ShutdownVmCommand:Perform: sending shutdown command for Desktop fc1.
>2013-02-28 10:58:06,155 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (pool-19-thread-48) START, DestroyVmVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, vmId=a4554520-43da-4575-9c6e-59c0feecdc02, force=false, secondsToWait=30, gracefully=true), log id: 3c818d59
>2013-02-28 10:58:06,164 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (pool-19-thread-48) START, DestroyVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, vmId=a4554520-43da-4575-9c6e-59c0feecdc02, force=false, secondsToWait=30, gracefully=true), log id: 1865d69f
>2013-02-28 10:58:06,186 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (pool-19-thread-48) FINISH, DestroyVDSCommand, log id: 1865d69f
>2013-02-28 10:58:06,201 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (pool-19-thread-48) FINISH, DestroyVmVDSCommand, return: PoweringDown, log id: 3c818d59
>2013-02-28 10:58:26,381 INFO  [org.ovirt.engine.core.bll.StopVmCommand] (pool-19-thread-49) Running command: StopVmCommand internal: false. Entities affected :  ID: a4554520-43da-4575-9c6e-59c0feecdc02 Type: VM
>2013-02-28 10:58:26,386 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (pool-19-thread-49) START, DestroyVmVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, vmId=a4554520-43da-4575-9c6e-59c0feecdc02, force=false, secondsToWait=0, gracefully=false), log id: 2336f67d
>2013-02-28 10:58:26,395 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (pool-19-thread-49) START, DestroyVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, vmId=a4554520-43da-4575-9c6e-59c0feecdc02, force=false, secondsToWait=0, gracefully=false), log id: a0fb585
>2013-02-28 10:58:27,396 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (pool-19-thread-49) FINISH, DestroyVDSCommand, log id: a0fb585
>2013-02-28 10:58:27,438 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (pool-19-thread-49) FINISH, DestroyVmVDSCommand, return: Down, log id: 2336f67d
>2013-02-28 10:58:33,365 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (http-0.0.0.0-8443-1) START, IsValidVDSCommand(storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 2aa3cd90
>2013-02-28 10:58:33,367 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (http-0.0.0.0-8443-1) FINISH, IsValidVDSCommand, return: true, log id: 2aa3cd90
>2013-02-28 10:58:33,394 INFO  [org.ovirt.engine.core.bll.RemoveVmCommand] (pool-19-thread-48) Lock Aquired to object a4554520-43da-4575-9c6e-59c0feecdc02
>2013-02-28 10:58:33,394 INFO  [org.ovirt.engine.core.bll.RemoveVmCommand] (pool-19-thread-48) Running command: RemoveVmCommand internal: false. Entities affected :  ID: a4554520-43da-4575-9c6e-59c0feecdc02 Type: VM
>2013-02-28 10:58:33,433 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-19-thread-48) START, SetVmStatusVDSCommand(vmId = a4554520-43da-4575-9c6e-59c0feecdc02, status = ImageLocked), log id: 52d8a496
>2013-02-28 10:58:33,459 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-19-thread-48) FINISH, SetVmStatusVDSCommand, log id: 52d8a496
>2013-02-28 10:58:33,500 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.RemoveVMVDSCommand] (pool-19-thread-48) START, RemoveVMVDSCommand(storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 00000000-0000-0000-0000-000000000000, vmGuid = a4554520-43da-4575-9c6e-59c0feecdc02), log id: 4d27c1ab
>2013-02-28 10:58:33,562 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.RemoveVMVDSCommand] (pool-19-thread-48) FINISH, RemoveVMVDSCommand, log id: 4d27c1ab
>2013-02-28 10:58:33,573 INFO  [org.ovirt.engine.core.bll.RemoveAllVmImagesCommand] (pool-19-thread-48) Running command: RemoveAllVmImagesCommand internal: true. Entities affected :  ID: a4554520-43da-4575-9c6e-59c0feecdc02 Type: VM
>2013-02-28 10:58:33,584 INFO  [org.ovirt.engine.core.bll.RemoveImageCommand] (pool-19-thread-48) Running command: RemoveImageCommand internal: true. Entities affected :  ID: 00000000-0000-0000-0000-000000000000 Type: Storage
>2013-02-28 10:58:33,587 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (pool-19-thread-48) START, DeleteImageGroupVDSCommand(storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, ignoreFailoverLimit = false, compatabilityVersion = 3.0, storageDomainId = c6c77717-201d-47fc-aff3-023806e79ac5, imageGroupId = 788f9532-0e38-491e-b842-ddc095a0aa16, postZeros = false, forceDelete = false), log id: 74f7fa25
>2013-02-28 10:58:35,202 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (pool-19-thread-48) FINISH, DeleteImageGroupVDSCommand, log id: 74f7fa25
>2013-02-28 10:58:35,211 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-19-thread-48) BaseAsyncTask::AddOrUpdateTaskInDB: Adding task d4882270-edc7-42c8-8fb4-1ce3bb56aa86 to DataBase
>2013-02-28 10:58:35,222 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-19-thread-48) EntityAsyncTask::Adding EntityMultiAsyncTasks object for entity a4554520-43da-4575-9c6e-59c0feecdc02
>2013-02-28 10:58:35,223 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (pool-19-thread-48) EntityMultiAsyncTasks::AttachTask: Attaching task d4882270-edc7-42c8-8fb4-1ce3bb56aa86 to entity a4554520-43da-4575-9c6e-59c0feecdc02.
>2013-02-28 10:58:35,223 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (pool-19-thread-48) AsyncTaskManager::AddTaskToManager: Adding task d4882270-edc7-42c8-8fb4-1ce3bb56aa86 (Parent Command RemoveVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2013-02-28 10:58:35,295 INFO  [org.ovirt.engine.core.bll.RemoveVmCommand] (pool-19-thread-48) Lock freed to object a4554520-43da-4575-9c6e-59c0feecdc02
>2013-02-28 10:58:35,325 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-19-thread-48) BaseAsyncTask::StartPollingTask: Starting to poll task d4882270-edc7-42c8-8fb4-1ce3bb56aa86.
>2013-02-28 10:58:35,325 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (pool-19-thread-48) EntityMultiAsyncTasks::StartPollingTask: Current Action Type for entity a4554520-43da-4575-9c6e-59c0feecdc02 is RemoveVm (determined by task d4882270-edc7-42c8-8fb4-1ce3bb56aa86)
>2013-02-28 10:58:45,172 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-73) AsyncTaskManager::PollAndUpdateAsyncTasks: 1 tasks, 1 tasks to poll now
>2013-02-28 10:58:45,198 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-73) SPMAsyncTask::PollTask: Polling task d4882270-edc7-42c8-8fb4-1ce3bb56aa86 (Parent Command RemoveVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2013-02-28 10:58:45,198 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-73) BaseAsyncTask::OnTaskEndSuccess: Task d4882270-edc7-42c8-8fb4-1ce3bb56aa86 (Parent Command RemoveVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2013-02-28 10:58:45,198 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-73) EntityAsyncTask::EndActionIfNecessary: All tasks of entity a4554520-43da-4575-9c6e-59c0feecdc02 has ended -> executing EndAction
>2013-02-28 10:58:45,198 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-73) EntityAsyncTask::EndAction: Ending action for 1 tasks (entity ID: a4554520-43da-4575-9c6e-59c0feecdc02): calling EndAction for action type RemoveVm.
>2013-02-28 10:58:45,198 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-19-thread-48) EntityAsyncTask::EndCommandAction [within thread]: Attempting to EndAction RemoveVm
>2013-02-28 10:58:45,200 INFO  [org.ovirt.engine.core.bll.RemoveVmCommand] (pool-19-thread-48) Ending command successfully: org.ovirt.engine.core.bll.RemoveVmCommand
>2013-02-28 10:58:45,200 INFO  [org.ovirt.engine.core.bll.RemoveVmCommand] (pool-19-thread-48) Lock Aquired to object a4554520-43da-4575-9c6e-59c0feecdc02
>2013-02-28 10:58:45,239 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-19-thread-48) START, SetVmStatusVDSCommand(vmId = a4554520-43da-4575-9c6e-59c0feecdc02, status = Down), log id: 1ceb93ec
>2013-02-28 10:58:45,254 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-19-thread-48) FINISH, SetVmStatusVDSCommand, log id: 1ceb93ec
>2013-02-28 10:58:45,279 INFO  [org.ovirt.engine.core.bll.MacPoolManager] (pool-19-thread-48) MacPoolManager::freeMac(mac = 00:1a:4a:42:68:00) - entered
>2013-02-28 10:58:45,286 INFO  [org.ovirt.engine.core.bll.RemoveVmCommand] (pool-19-thread-48) Lock freed to object a4554520-43da-4575-9c6e-59c0feecdc02
>2013-02-28 10:58:45,298 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-19-thread-48) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type RemoveVm completed, handling the result.
>2013-02-28 10:58:45,299 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-19-thread-48) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type RemoveVm succeeded, clearing tasks.
>2013-02-28 10:58:45,299 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-19-thread-48) SPMAsyncTask::ClearAsyncTask: Attempting to clear task d4882270-edc7-42c8-8fb4-1ce3bb56aa86
>2013-02-28 10:58:45,300 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-19-thread-48) START, SPMClearTaskVDSCommand(storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, ignoreFailoverLimit = false, compatabilityVersion = null, taskId = d4882270-edc7-42c8-8fb4-1ce3bb56aa86), log id: 59857d4f
>2013-02-28 10:58:45,303 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-19-thread-48) START, HSMClearTaskVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, taskId=d4882270-edc7-42c8-8fb4-1ce3bb56aa86), log id: 19b05f03
>2013-02-28 10:58:45,322 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-19-thread-48) FINISH, HSMClearTaskVDSCommand, log id: 19b05f03
>2013-02-28 10:58:45,322 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-19-thread-48) FINISH, SPMClearTaskVDSCommand, log id: 59857d4f
>2013-02-28 10:58:45,328 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-19-thread-48) BaseAsyncTask::RemoveTaskFromDB: Removing task d4882270-edc7-42c8-8fb4-1ce3bb56aa86 from DataBase
>2013-02-28 10:58:45,335 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-19-thread-48) EntityAsyncTask::HandleEndActionResult [within thread]: Removing EntityMultiAsyncTasks object for entity a4554520-43da-4575-9c6e-59c0feecdc02
>2013-02-28 10:59:10,193 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:59:10,195 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:59:10,203 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:59:10,204 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:59:10,212 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-02-28 10:59:15,189 INFO  [org.ovirt.engine.core.bll.MaintananceNumberOfVdssCommand] (pool-19-thread-50) Running command: MaintananceNumberOfVdssCommand internal: false. Entities affected :  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 10:59:15,191 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-50) START, SetVdsStatusVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, status=PreparingForMaintenance, nonOperationalReason=NONE), log id: 62cdf02b
>2013-02-28 10:59:15,207 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-50) SetVdsStatusVDSCommand::VSD dell-pet105-01.qe.lab.eng.nay.redhat.com is spm and moved from up calling ResetIrs.
>2013-02-28 10:59:15,221 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.ResetIrsVDSCommand] (pool-19-thread-50) START, ResetIrsVDSCommand(storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, ignoreFailoverLimit = false, compatabilityVersion = null, hostName = 10.66.72.21, vdsId = c78adde2-814b-11e2-9202-001a4a420b20, ignoreStopFailed = false), log id: 44be9585
>2013-02-28 10:59:15,227 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand] (pool-19-thread-50) START, SpmStopVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed), log id: 4492d963
>2013-02-28 10:59:15,247 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand] (pool-19-thread-50) SpmStopVDSCommand::Stopping SPM on vds dell-pet105-01.qe.lab.eng.nay.redhat.com, pool id 32d0d070-ad0c-4383-8f69-ea2a806a8eed
>2013-02-28 10:59:17,227 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand] (pool-19-thread-50) FINISH, SpmStopVDSCommand, log id: 4492d963
>2013-02-28 10:59:17,236 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.ResetIrsVDSCommand] (pool-19-thread-50) FINISH, ResetIrsVDSCommand, log id: 44be9585
>2013-02-28 10:59:17,241 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-50) FINISH, SetVdsStatusVDSCommand, log id: 62cdf02b
>2013-02-28 10:59:17,299 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-47) vds::Updated vds status from Preparing for Maintenance to Maintenance in database,  vds = c78adde2-814b-11e2-9202-001a4a420b20 : dell-pet105-01.qe.lab.eng.nay.redhat.com
>2013-02-28 10:59:17,321 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (QuartzScheduler_Worker-47) Clearing cache of pool: 32d0d070-ad0c-4383-8f69-ea2a806a8eed for problematic entities of VDS: dell-pet105-01.qe.lab.eng.nay.redhat.com.
>2013-02-28 10:59:17,324 INFO  [org.ovirt.engine.core.bll.MaintananceVdsCommand] (pool-19-thread-50) Running command: MaintananceVdsCommand internal: true. Entities affected :  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 10:59:17,343 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStoragePoolVDSCommand] (QuartzScheduler_Worker-47) START, DisconnectStoragePoolVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, vds_spm_id = 1, masterDomainId = 00000000-0000-0000-0000-000000000000, masterVersion = 0), log id: 42527357
>2013-02-28 10:59:18,526 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStoragePoolVDSCommand] (QuartzScheduler_Worker-47) FINISH, DisconnectStoragePoolVDSCommand, log id: 42527357
>2013-02-28 10:59:18,547 INFO  [org.ovirt.engine.core.bll.storage.DisconnectHostFromStoragePoolServersCommand] (QuartzScheduler_Worker-47) Running command: DisconnectHostFromStoragePoolServersCommand internal: true. Entities affected :  ID: 32d0d070-ad0c-4383-8f69-ea2a806a8eed Type: StoragePool
>2013-02-28 10:59:18,607 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (QuartzScheduler_Worker-47) START, DisconnectStorageServerVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20, storagePoolId = 32d0d070-ad0c-4383-8f69-ea2a806a8eed, storageType = FCP, connectionList = []), log id: 6e6d6191
>2013-02-28 10:59:20,752 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (QuartzScheduler_Worker-47) FINISH, DisconnectStorageServerVDSCommand, return: {}, log id: 6e6d6191
>2013-02-28 10:59:22,178 INFO  [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (QuartzScheduler_Worker-77) Running command: SetStoragePoolStatusCommand internal: true. Entities affected :  ID: 32d0d070-ad0c-4383-8f69-ea2a806a8eed Type: StoragePool
>2013-02-28 10:59:56,565 INFO  [org.ovirt.engine.core.bll.storage.RemoveStoragePoolCommand] (http-0.0.0.0-8443-1) Running command: RemoveStoragePoolCommand internal: false. Entities affected :  ID: 32d0d070-ad0c-4383-8f69-ea2a806a8eed Type: StoragePool
>2013-02-28 11:00:00,461 INFO  [org.ovirt.engine.core.bll.RemoveVdsCommand] (pool-19-thread-47) Running command: RemoveVdsCommand internal: false. Entities affected :  ID: c78adde2-814b-11e2-9202-001a4a420b20 Type: VDS
>2013-02-28 11:00:00,490 INFO  [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (pool-19-thread-47) START, RemoveVdsVDSCommand(vdsId = c78adde2-814b-11e2-9202-001a4a420b20), log id: 11b2bee
>2013-02-28 11:00:00,490 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (pool-19-thread-47) vdsManager::disposing
>2013-02-28 11:00:00,491 INFO  [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (pool-19-thread-47) FINISH, RemoveVdsVDSCommand, log id: 11b2bee
>2013-02-28 11:00:15,070 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-25) AsyncTaskManager::SetNewMap: The map contains now 0 tasks
>2013-02-28 11:00:15,070 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-25) AsyncTaskManager::RemoveOldAndCleanedTasks: Cleared all tasks of pool 32d0d070-ad0c-4383-8f69-ea2a806a8eed.
>2013-02-28 11:01:50,073 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-46) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 11:32:19,716 WARN  [org.ovirt.engine.core.bll.BackendCallBacksDirector] (pool-19-thread-47) Client did not poll async queries updates for 30 cycles, suspending server side updates for session id = 0D0D32886B39799347F2D9A225656268
>2013-02-28 11:32:20,717 WARN  [org.ovirt.engine.core.bll.BackendCallBacksDirector] (pool-19-thread-47) Client did not poll async queries updates for 30 cycles sessionId = 0D0D32886B39799347F2D9A225656268. Callback will be removed.
>2013-02-28 12:01:50,078 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-37) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 13:01:50,083 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-44) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 14:01:50,086 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-1) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 15:01:50,090 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-55) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 16:01:50,093 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-69) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 17:01:50,098 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-86) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 18:01:50,101 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-14) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 19:01:50,106 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-85) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 20:01:50,110 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-99) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 21:01:50,116 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-94) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 22:01:50,120 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-20) DbUserCacheManager::refreshAllUserData() - entered
>2013-02-28 23:01:50,125 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-36) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 00:01:50,129 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-85) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 01:01:50,134 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-29) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 02:01:50,138 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-85) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 03:01:50,143 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-14) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 03:35:35,002 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (QuartzScheduler_Worker-49) AuditLogCleanupManager::deleteAgedOutAuditLogs - entered
>2013-03-01 04:01:50,148 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-48) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 05:01:50,152 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-45) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 06:01:50,157 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-11) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 07:01:50,162 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-97) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 08:01:50,168 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-57) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 09:01:50,172 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-82) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 10:01:50,177 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-20) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 11:01:50,182 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-23) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 12:01:50,187 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-67) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 13:01:50,191 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-36) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 14:01:50,195 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-16) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 15:01:50,200 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-29) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 16:01:50,205 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-84) DbUserCacheManager::refreshAllUserData() - entered
>2013-03-01 16:17:05,583 INFO  [org.ovirt.engine.core.bll.LoginAdminUserCommand] (http-0.0.0.0-8443-2) Running command: LoginAdminUserCommand internal: false.
>2013-03-01 16:17:05,650 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,653 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,690 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,692 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,698 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,701 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,706 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,709 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,717 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,719 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,751 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,759 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,769 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,771 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,778 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,780 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,787 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,793 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,796 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,804 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,806 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,810 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:05,816 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:18,377 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:18,454 INFO  [org.ovirt.engine.core.bll.storage.AddEmptyStoragePoolCommand] (http-0.0.0.0-8443-2) Running command: AddEmptyStoragePoolCommand internal: false. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2013-03-01 16:17:19,326 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:19,333 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:22,131 INFO  [org.ovirt.engine.core.bll.AddVdsGroupCommand] (http-0.0.0.0-8443-2) Running command: AddVdsGroupCommand internal: false. Entities affected :  ID: 4c0e062a-fd20-4406-b3a6-31c227422825 Type: StoragePool
>2013-03-01 16:17:56,881 INFO  [org.ovirt.engine.core.bll.RegisterVdsQuery] (http-0.0.0.0-8443-2) Running Command: RegisterVds
>2013-03-01 16:17:56,987 INFO  [org.ovirt.engine.core.bll.AddVdsCommand] (pool-19-thread-47) Running command: AddVdsCommand internal: true. Entities affected :  ID: 99408929-82cf-4dc7-a532-9d998063fa95 Type: VdsGroups
>2013-03-01 16:17:57,024 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (pool-19-thread-47) Running command: AddVdsSpmIdCommand internal: true. Entities affected :  ID: 85e23806-8248-11e2-9f62-001a4a420b20 Type: VDS
>2013-03-01 16:17:57,035 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-19-thread-47) No string for UNASSIGNED type. Use default Log
>2013-03-01 16:17:57,037 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (pool-19-thread-47) Cannot get vdsManager for vdsid=85e23806-8248-11e2-9f62-001a4a420b20
>2013-03-01 16:17:57,037 INFO  [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (pool-19-thread-47) START, RemoveVdsVDSCommand(vdsId = 85e23806-8248-11e2-9f62-001a4a420b20), log id: 44ba6ef
>2013-03-01 16:17:57,037 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (pool-19-thread-47) Cannot get vdsManager for vdsid=85e23806-8248-11e2-9f62-001a4a420b20
>2013-03-01 16:17:57,037 INFO  [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (pool-19-thread-47) FINISH, RemoveVdsVDSCommand, log id: 44ba6ef
>2013-03-01 16:17:57,038 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (pool-19-thread-47) Cannot get vdsManager for vdsid=85e23806-8248-11e2-9f62-001a4a420b20
>2013-03-01 16:17:57,039 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (pool-19-thread-47) START, AddVdsVDSCommand(vdsId = 85e23806-8248-11e2-9f62-001a4a420b20), log id: 2bc971d
>2013-03-01 16:17:57,039 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (pool-19-thread-47) AddVds - entered , starting logic to add VDS 85e23806-8248-11e2-9f62-001a4a420b20
>2013-03-01 16:17:57,042 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (pool-19-thread-47) AddVds - VDS 85e23806-8248-11e2-9f62-001a4a420b20 was added, will try to add it to the resource manager
>2013-03-01 16:17:57,042 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (pool-19-thread-47) Eneterd VdsManager:constructor
>2013-03-01 16:17:57,042 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (pool-19-thread-47) vdsBroker(10.66.110.242,54,321)
>2013-03-01 16:17:57,046 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (pool-19-thread-47) ResourceManager::AddVds - VDS 85e23806-8248-11e2-9f62-001a4a420b20 was added to the Resource Manager
>2013-03-01 16:17:57,046 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (pool-19-thread-47) FINISH, AddVdsVDSCommand, log id: 2bc971d
>2013-03-01 16:17:57,067 INFO  [org.ovirt.engine.core.bll.RegisterVdsQuery] (http-0.0.0.0-8443-2) RegisterVdsQuery::Register - Registered a new VDS pending approval - Name: dell-per510-01.qe.lab.eng.nay.redhat.com, Hostname: 10.66.110.242, UniqueID: 4C4C4544-0058-3810-8033-B2C04F363358_00:1b:21:36:79:f0
>2013-03-01 16:17:57,098 INFO  [org.ovirt.engine.core.register.RegisterServlet] (http-0.0.0.0-8443-2) Succeeded to run RegisterVds.
>2013-03-01 16:17:57,332 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:58,971 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:58,978 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:58,981 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:58,986 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:17:58,989 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http-0.0.0.0-8443-1) calling GetConfigurationValueQuery with null version, using default general for version
>2013-03-01 16:18:04,992 INFO  [org.ovirt.engine.core.bll.ChangeVDSClusterCommand] (http-0.0.0.0-8443-1) Running command: ChangeVDSClusterCommand internal: false. Entities affected :  ID: 711add10-8248-11e2-9b66-001a4a420b20 Type: VdsGroups,  ID: 85e23806-8248-11e2-9f62-001a4a420b20 Type: VDS
>2013-03-01 16:18:05,024 INFO  [org.ovirt.engine.core.bll.RemoveVdsSpmIdCommand] (http-0.0.0.0-8443-1) Running command: RemoveVdsSpmIdCommand internal: true. Entities affected :  ID: 85e23806-8248-11e2-9f62-001a4a420b20 Type: VDS
>2013-03-01 16:18:05,041 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (http-0.0.0.0-8443-1) Running command: AddVdsSpmIdCommand internal: true. Entities affected :  ID: 85e23806-8248-11e2-9f62-001a4a420b20 Type: VDS
>2013-03-01 16:18:05,050 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http-0.0.0.0-8443-1) No string for UNASSIGNED type. Use default Log
>2013-03-01 16:18:05,122 INFO  [org.ovirt.engine.core.bll.UpdateVdsCommand] (http-0.0.0.0-8443-1) Running command: UpdateVdsCommand internal: false. Entities affected :  ID: 85e23806-8248-11e2-9f62-001a4a420b20 Type: VDS
>2013-03-01 16:18:05,214 INFO  [org.ovirt.engine.core.bll.ApproveVdsCommand] (pool-19-thread-47) Running command: ApproveVdsCommand internal: false. Entities affected :  ID: 85e23806-8248-11e2-9f62-001a4a420b20 Type: VDS
>2013-03-01 16:18:05,214 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-19-thread-47) Before Installation pool-19-thread-47, Powerclient/oVirtNode case: setting status to installing
>2013-03-01 16:18:05,215 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-47) START, SetVdsStatusVDSCommand(vdsId = 85e23806-8248-11e2-9f62-001a4a420b20, status=Installing, nonOperationalReason=NONE), log id: 7f8610fe
>2013-03-01 16:18:05,235 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-47) FINISH, SetVdsStatusVDSCommand, log id: 7f8610fe
>2013-03-01 16:18:05,249 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-19-thread-47) Before Installation pool-19-thread-47
>2013-03-01 16:18:05,249 INFO  [org.ovirt.engine.core.bll.CBCInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Executing oVirt installation stage. (Stage: Starting Host installation)
>2013-03-01 16:18:05,249 INFO  [org.ovirt.engine.core.bll.CBCInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Executing oVirt installation stage. (Stage: Connecting to Host)
>2013-03-01 16:18:05,989 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Recieved message: <BSTRAP component='RHEV_INSTALL' status='OK' message='Connected to Host 10.66.110.242 with SSH key fingerprint: 0b:dd:04:27:bf:d7:db:82:9f:f9:62:4f:1b:8f:ad:42'/>. FYI. (Stage: Connecting to Host)
>2013-03-01 16:18:06,003 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Successfully connected to server ssh. (Stage: Connecting to Host)
>2013-03-01 16:18:06,003 INFO  [org.ovirt.engine.core.bll.CBCInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Executing oVirt installation stage. (Stage: Running upgrade / reinstall script on oVirt Node)
>2013-03-01 16:18:06,003 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) Invoking /usr/share/vdsm-reg/vdsm-gen-cert -O "rhevhhh" 10.66.110.242 8e8ece88-c98f-4d1c-a94a-46a9ae7d1d8a on 10.66.110.242
>2013-03-01 16:18:07,093 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-47) Initializing Host: dell-per510-01.qe.lab.eng.nay.redhat.com
>2013-03-01 16:18:19,007 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Recieved message: <BSTRAP component='Encryption setup' status='OK'/>
><BSTRAP component='RHEV_INSTALL' status='OK'/>
>. Stage completed. (Stage: Running upgrade / reinstall script on oVirt Node)
>2013-03-01 16:18:19,030 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) RunSSHCommand returns true
>2013-03-01 16:18:19,030 INFO  [org.ovirt.engine.core.bll.CBCInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Executing oVirt installation stage. (Stage: Downloading certificate request from Host)
>2013-03-01 16:18:19,031 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) Downloading file /tmp/cert_8e8ece88-c98f-4d1c-a94a-46a9ae7d1d8a.req from 10.66.110.242 to /etc/pki/rhevm/requests/cert_8e8ece88-c98f-4d1c-a94a-46a9ae7d1d8a.req
>2013-03-01 16:18:19,726 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47) Installation of 10.66.110.242. successfully done sftp operation ( Stage: Downloading certificate request from Host)
>2013-03-01 16:18:19,727 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) return true
>2013-03-01 16:18:19,727 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47)  DownloadCertificateRequest ended:true
>2013-03-01 16:18:19,727 INFO  [org.ovirt.engine.core.bll.CBCInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Executing oVirt installation stage. (Stage: Sign certificate request and generate certificate)
>2013-03-01 16:18:20,740 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47)  SignCertificateRequest ended:true
>2013-03-01 16:18:20,740 INFO  [org.ovirt.engine.core.bll.CBCInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Executing oVirt installation stage. (Stage: Upload signed sertificate to Host)
>2013-03-01 16:18:20,740 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) Uploading file /etc/pki/rhevm/certs/10.66.110.242cert.pem to /tmp/cert_8e8ece88-c98f-4d1c-a94a-46a9ae7d1d8a.pem on 10.66.110.242
>2013-03-01 16:18:20,740 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) Uploading file /etc/pki/rhevm/certs/10.66.110.242cert.pem to /tmp/cert_8e8ece88-c98f-4d1c-a94a-46a9ae7d1d8a.pem on 10.66.110.242
>2013-03-01 16:18:20,995 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47) Installation of 10.66.110.242. successfully done sftp operation ( Stage: Upload signed sertificate to Host)
>2013-03-01 16:18:20,995 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) return true
>2013-03-01 16:18:20,995 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47)  UploadSignedCertificate ended:true
>2013-03-01 16:18:20,995 INFO  [org.ovirt.engine.core.bll.CBCInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Executing oVirt installation stage. (Stage: Upload Cerficate Autority to Host)
>2013-03-01 16:18:20,996 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) Uploading file /etc/pki/rhevm/ca.pem to /tmp/CA_8e8ece88-c98f-4d1c-a94a-46a9ae7d1d8a.pem on 10.66.110.242
>2013-03-01 16:18:20,996 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) Uploading file /etc/pki/rhevm/ca.pem to /tmp/CA_8e8ece88-c98f-4d1c-a94a-46a9ae7d1d8a.pem on 10.66.110.242
>2013-03-01 16:18:21,210 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47) Installation of 10.66.110.242. successfully done sftp operation ( Stage: Upload Cerficate Autority to Host)
>2013-03-01 16:18:21,210 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) return true
>2013-03-01 16:18:21,210 INFO  [org.ovirt.engine.core.bll.CBCInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Executing oVirt installation stage, sending SSH Command /usr/share/vdsm-reg/vdsm-complete -c 'ssl=true' 8e8ece88-c98f-4d1c-a94a-46a9ae7d1d8a 0. (Stage: Running second installation script on Host)
>2013-03-01 16:18:21,210 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) Invoking /usr/share/vdsm-reg/vdsm-complete -c 'ssl=true' 8e8ece88-c98f-4d1c-a94a-46a9ae7d1d8a 0 on 10.66.110.242
>2013-03-01 16:18:24,567 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Recieved message: <BSTRAP component='instCert' status='OK'/>
><BSTRAP component='CoreDump' status='OK'/>
><BSTRAP component='cleanAll' status='OK'/>
><BSTRAP component='VDS Configuration' status='OK'/>
><BSTRAP component='Restart' status='OK' message='Restarting vdsmd service' />
><BSTRAP component='RHEV_INSTALL' status='OK'/>
>. Stage completed. (Stage: Running second installation script on Host)
>2013-03-01 16:18:24,610 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) RunSSHCommand returns true
>2013-03-01 16:18:24,611 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-19-thread-47) After Installation pool-19-thread-47
>2013-03-01 16:18:24,612 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-47) START, SetVdsStatusVDSCommand(vdsId = 85e23806-8248-11e2-9f62-001a4a420b20, status=Reboot, nonOperationalReason=NONE), log id: 7f1f29a6
>2013-03-01 16:18:24,620 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-47) FINISH, SetVdsStatusVDSCommand, log id: 7f1f29a6
>2013-03-01 16:18:24,621 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-50) START, SetVdsStatusVDSCommand(vdsId = 85e23806-8248-11e2-9f62-001a4a420b20, status=NonResponsive, nonOperationalReason=NONE), log id: 2db55a6
>2013-03-01 16:18:24,624 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-47) START, SetVdsStatusVDSCommand(vdsId = 85e23806-8248-11e2-9f62-001a4a420b20, status=Unassigned, nonOperationalReason=NONE), log id: 22f2bfd5
>2013-03-01 16:18:24,631 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-50) FINISH, SetVdsStatusVDSCommand, log id: 2db55a6
>2013-03-01 16:18:24,639 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-47) FINISH, SetVdsStatusVDSCommand, log id: 22f2bfd5
>2013-03-01 16:18:28,478 INFO  [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (QuartzScheduler_Worker-58) Running command: InitVdsOnUpCommand internal: true.
>2013-03-01 16:18:28,494 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (QuartzScheduler_Worker-58) No string for UNASSIGNED type. Use default Log
>2013-03-01 16:18:28,497 INFO  [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-19-thread-47) MultipleActionsRunner of type MigrateVmToServer invoked with no actions
>2013-03-01 16:18:28,517 INFO  [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (QuartzScheduler_Worker-58) Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected :  ID: 85e23806-8248-11e2-9f62-001a4a420b20 Type: VDS
>2013-03-01 16:18:28,527 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (QuartzScheduler_Worker-58) Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: 85e23806-8248-11e2-9f62-001a4a420b20 Type: VDS
>2013-03-01 16:18:34,549 INFO  [org.ovirt.engine.core.bll.MaintananceNumberOfVdssCommand] (pool-19-thread-50) Running command: MaintananceNumberOfVdssCommand internal: false. Entities affected :  ID: 85e23806-8248-11e2-9f62-001a4a420b20 Type: VDS
>2013-03-01 16:18:34,551 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-50) START, SetVdsStatusVDSCommand(vdsId = 85e23806-8248-11e2-9f62-001a4a420b20, status=PreparingForMaintenance, nonOperationalReason=NONE), log id: 6008570e
>2013-03-01 16:18:34,567 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-50) FINISH, SetVdsStatusVDSCommand, log id: 6008570e
>2013-03-01 16:18:34,577 INFO  [org.ovirt.engine.core.bll.MaintananceVdsCommand] (pool-19-thread-50) Running command: MaintananceVdsCommand internal: true. Entities affected :  ID: 85e23806-8248-11e2-9f62-001a4a420b20 Type: VDS
>2013-03-01 16:18:34,704 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-29) vds::Updated vds status from Preparing for Maintenance to Maintenance in database,  vds = 85e23806-8248-11e2-9f62-001a4a420b20 : dell-per510-01.qe.lab.eng.nay.redhat.com
>2013-03-01 16:18:41,219 INFO  [org.ovirt.engine.core.bll.UpdateVdsCommand] (http-0.0.0.0-8443-2) Running command: UpdateVdsCommand internal: false. Entities affected :  ID: 85e23806-8248-11e2-9f62-001a4a420b20 Type: VDS
>2013-03-01 16:18:41,240 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (http-0.0.0.0-8443-2) START, SetVdsStatusVDSCommand(vdsId = 85e23806-8248-11e2-9f62-001a4a420b20, status=Installing, nonOperationalReason=NONE), log id: 4f0a99c0
>2013-03-01 16:18:41,250 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (http-0.0.0.0-8443-2) FINISH, SetVdsStatusVDSCommand, log id: 4f0a99c0
>2013-03-01 16:18:41,256 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-19-thread-47) Running command: InstallVdsCommand internal: true. Entities affected :  ID: 85e23806-8248-11e2-9f62-001a4a420b20 Type: VDS
>2013-03-01 16:18:41,256 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-19-thread-47) Before Installation pool-19-thread-47, Powerclient/oVirtNode case: setting status to installing
>2013-03-01 16:18:41,258 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-47) START, SetVdsStatusVDSCommand(vdsId = 85e23806-8248-11e2-9f62-001a4a420b20, status=Installing, nonOperationalReason=NONE), log id: 431ab9a6
>2013-03-01 16:18:41,291 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-47) FINISH, SetVdsStatusVDSCommand, log id: 431ab9a6
>2013-03-01 16:18:41,303 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-19-thread-47) Before Installation pool-19-thread-47
>2013-03-01 16:18:41,304 INFO  [org.ovirt.engine.core.bll.OVirtInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Executing oVirt reinstall/upgrade stage. (Stage: Starting Host installation)
>2013-03-01 16:18:41,304 INFO  [org.ovirt.engine.core.bll.OVirtInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Executing oVirt reinstall/upgrade stage. (Stage: Connecting to Host)
>2013-03-01 16:18:41,420 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Recieved message: <BSTRAP component='RHEV_INSTALL' status='OK' message='Connected to Host 10.66.110.242 with SSH key fingerprint: 0b:dd:04:27:bf:d7:db:82:9f:f9:62:4f:1b:8f:ad:42'/>. FYI. (Stage: Connecting to Host)
>2013-03-01 16:18:41,432 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Successfully connected to server ssh. (Stage: Connecting to Host)
>2013-03-01 16:18:41,432 INFO  [org.ovirt.engine.core.bll.OVirtInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Executing oVirt reinstall/upgrade stage. (Stage: Upload upgrade ISO to oVirt Node)
>2013-03-01 16:18:41,432 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) Uploading file /usr/share/rhev-hypervisor/rhevh-6.4-20130227.0.el6_4.iso to /data/updates/ovirt-node-image.iso on 10.66.110.242
>2013-03-01 16:18:41,433 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Recieved message: Preparing ISO file. FYI. (Stage: Upload upgrade ISO to oVirt Node)
>2013-03-01 16:18:41,433 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (pool-19-thread-47) VDS message: Preparing ISO file
>2013-03-01 16:18:42,048 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Recieved message: Determining current address from host perspective. FYI. (Stage: Upload upgrade ISO to oVirt Node)
>2013-03-01 16:18:42,048 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (pool-19-thread-47) VDS message: Determining current address from host perspective
>2013-03-01 16:18:42,048 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) getLocalIP entry
>2013-03-01 16:18:42,094 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) getLocalIP return: 10.66.104.178
>
>2013-03-01 16:18:42,095 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Recieved message: Starting ISO upload. FYI. (Stage: Upload upgrade ISO to oVirt Node)
>2013-03-01 16:18:42,095 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (pool-19-thread-47) VDS message: Starting ISO upload
>2013-03-01 16:18:44,698 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Recieved message: ISO upload ended successfully. FYI. (Stage: Upload upgrade ISO to oVirt Node)
>2013-03-01 16:18:44,698 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (pool-19-thread-47) VDS message: ISO upload ended successfully
>2013-03-01 16:18:44,698 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47) Installation of 10.66.110.242. successfully done sftp operation ( Stage: Upload upgrade ISO to oVirt Node)
>2013-03-01 16:18:44,741 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) uploadLargeFile returns true
>2013-03-01 16:18:44,741 INFO  [org.ovirt.engine.core.bll.OVirtInstaller] (pool-19-thread-47) Installation of 10.66.110.242. Executing oVirt reinstall/upgrade stage. (Stage: Running upgrade / reinstall script on oVirt Node)
>2013-03-01 16:18:44,741 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) Invoking /usr/share/vdsm-reg/vdsm-upgrade on 10.66.110.242
>2013-03-01 16:28:44,902 ERROR [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) The required action is taking longer than allowed by configuration. Verify host networking and storage settings. Current timeout is set to: 600000
>2013-03-01 16:28:44,903 ERROR [org.ovirt.engine.core.bll.VdsInstaller] (pool-19-thread-47) Installation of 10.66.110.242 has failed. Failure details: The required action is taking longer than allowed by configuration. Verify host networking and storage settings.. (Stage: Running upgrade / reinstall script on oVirt Node)
>2013-03-01 16:28:44,903 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (pool-19-thread-47) VDS message: The required action is taking longer than allowed by configuration. Verify host networking and storage settings.
>2013-03-01 16:28:44,903 ERROR [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) Error running command /usr/share/vdsm-reg/vdsm-upgrade
>javax.naming.TimeLimitExceededException: The required action is taking longer than allowed by configuration. Verify host networking and storage settings.
>	at org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper.RunSSHCommand(MinaInstallWrapper.java:200)
>	at org.ovirt.engine.core.bll.OVirtInstaller.RunStage(OVirtInstaller.java:54)
>	at org.ovirt.engine.core.bll.VdsInstaller.Install(VdsInstaller.java:207)
>	at org.ovirt.engine.core.bll.InstallVdsCommand.executeCommand(InstallVdsCommand.java:113)
>	at org.ovirt.engine.core.bll.CommandBase.ExecuteWithoutTransaction(CommandBase.java:629)
>	at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:718)
>	at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1022)
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:168)
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:107)
>	at org.ovirt.engine.core.bll.CommandBase.Execute(CommandBase.java:733)
>	at org.ovirt.engine.core.bll.CommandBase.ExecuteAction(CommandBase.java:207)
>	at org.ovirt.engine.core.bll.MultipleActionsRunner.RunCommands(MultipleActionsRunner.java:140)
>	at org.ovirt.engine.core.bll.MultipleActionsRunner$1.run(MultipleActionsRunner.java:61)
>	at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:52)
>	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:439)
>	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
>	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
>	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
>	at java.lang.Thread.run(Thread.java:662)
>2013-03-01 16:28:44,905 INFO  [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-19-thread-47) RunSSHCommand returns false
>2013-03-01 16:28:44,906 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-19-thread-47) After Installation pool-19-thread-47
>2013-03-01 16:28:44,908 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-47) START, SetVdsStatusVDSCommand(vdsId = 85e23806-8248-11e2-9f62-001a4a420b20, status=InstallFailed, nonOperationalReason=NONE), log id: db0b8c7
>2013-03-01 16:28:44,928 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-19-thread-47) FINISH, SetVdsStatusVDSCommand, log id: db0b8c7

Actions: View

Attachments on bug 916485: 703829 | 703830 | 704089