Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 669599 Details for
Bug 886087
Rest query add storage domain fails on fedora18: missing /sbin/scsi_id
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
engine.log
engine.log (text/x-log), 239.45 KB, created by
Ohad Basan
on 2012-12-27 13:42:06 UTC
(
hide
)
Description:
engine.log
Filename:
MIME Type:
Creator:
Ohad Basan
Created:
2012-12-27 13:42:06 UTC
Size:
239.45 KB
patch
obsolete
>2012-12-11 10:34:23,693 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Start time: 12/11/12 10:34 AM >2012-12-11 10:34:23,792 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: CbcCheckOnVdsChange >2012-12-11 10:34:23,815 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: CAEngineKey >2012-12-11 10:34:23,815 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: ENGINEEARLib >2012-12-11 10:34:23,833 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: MinimalETLVersion >2012-12-11 10:34:24,136 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: ScriptsPath >2012-12-11 10:34:24,143 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: SQLServerI18NPrefix >2012-12-11 10:34:24,510 ERROR [org.ovirt.engine.core.engineencryptutils.EncryptionUtils] (MSC service thread 1-2) Failed to decrypt Data must start with zero >2012-12-11 10:34:24,510 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-2) Failed to decrypt value for property TruststorePass will be used encrypted value >2012-12-11 10:34:24,528 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-2) Could not parse option AutoRecoveryAllowedTypes value. >2012-12-11 10:34:24,768 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) VDSBrokerFrontend: 12/11/12 10:34 AM >2012-12-11 10:34:24,777 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) CpuFlagsManager: 12/11/12 10:34 AM >2012-12-11 10:34:24,778 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Setting audit clean up manager to run at: 35 35 3 * * ? >2012-12-11 10:34:24,814 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) AuditLogCleanupManager: 12/11/12 10:34 AM >2012-12-11 10:34:24,817 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-2) TagsDirector initialization >2012-12-11 10:34:24,855 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-2) Tag root added to tree >2012-12-11 10:34:24,882 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) TagsDirector: 12/11/12 10:34 AM >2012-12-11 10:34:24,901 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) IsoDomainListSyncronizer: 12/11/12 10:34 AM >2012-12-11 10:34:24,937 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) InitHandlers: 12/11/12 10:34 AM >2012-12-11 10:34:24,960 WARN [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-2) Code VDS_FENCING_OPERATION_FAILED appears more then once in string table. >2012-12-11 10:34:24,961 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) ErrorTranslator: 12/11/12 10:34 AM >2012-12-11 10:34:24,961 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) VdsErrorTranslator: 12/11/12 10:34 AM >2012-12-11 10:34:24,966 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Mark uncompleted jobs as UNKNOWN: 12/11/12 10:34 AM >2012-12-11 10:34:25,003 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) JobRepositoryCleanupManager: 12/11/12 10:34 AM >2012-12-11 10:34:25,005 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) AutoRecoveryManager: 12/11/12 10:34 AM >2012-12-11 10:34:25,011 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) ExecutionMessageDirector: 12/11/12 10:34 AM >2012-12-11 10:34:25,037 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) InitResourceManager: 12/11/12 10:34 AM >2012-12-11 10:34:25,038 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-1) ResourceManager::ResourceManager::Entered >2012-12-11 10:34:25,041 INFO [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-2) UsersDomainsCacheManager: 12/11/12 10:34 AM >2012-12-11 10:34:25,203 INFO [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-2) DbUserCacheManager: 12/11/12 10:34 AM >2012-12-11 10:34:25,250 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-1) DbUserCacheManager::refreshAllUserData() - entered >2012-12-11 10:34:25,253 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-1) Eneterd VdsManager:constructor >2012-12-11 10:34:25,253 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-1) vdsBroker(10.35.148.46,54,321) >2012-12-11 10:34:25,469 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-1) ResourceManager::AddVds - VDS 0ffc27a0-961f-4501-acc4-d5d84ed938f5 was added to the Resource Manager >2012-12-11 10:34:25,511 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (MSC service thread 1-1) AsyncTaskManager: Initialization of AsyncTaskManager completed successfully. >2012-12-11 10:34:25,512 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) AsyncTaskManager: 12/11/12 10:34 AM >2012-12-11 10:34:25,516 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) VdsLoadBalancer: 12/11/12 10:34 AM >2012-12-11 10:34:25,525 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (pool-3-thread-1) MacPoolManager started: 12/11/12 10:34 AM >2012-12-11 10:34:25,559 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) Init VM Custom Properties utilities: 12/11/12 10:34 AM >2012-12-11 10:34:25,595 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (pool-3-thread-1) MacPoolManager finished: 12/11/12 10:34 AM >2012-12-11 10:34:27,545 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-2) Initializing Host: cinteg04.ci.lab.tlv.redhat.com >2012-12-11 10:35:00,005 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-35) Autorecovering hosts is disabled, skipping >2012-12-11 10:35:00,008 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-35) Autorecovering storage domains is disabled, skipping >2012-12-11 10:37:46,937 WARN [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) CanDoAction of action LoginUser failed. Reasons:USER_NOT_AUTHORIZED_TO_PERFORM_ACTION >2012-12-11 10:37:46,938 INFO [org.ovirt.engine.api.restapi.security.auth.LoginValidator] (ajp--127.0.0.1-8702-2) Login failure, user: yzaslav domain: brq-ipa.rhev.lab.eng.brq.redhat.com reason: [USER_NOT_AUTHORIZED_TO_PERFORM_ACTION] >2012-12-11 10:40:00,003 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-53) Autorecovering hosts is disabled, skipping >2012-12-11 10:40:00,004 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-53) Autorecovering storage domains is disabled, skipping >2012-12-11 10:45:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-72) Autorecovering hosts is disabled, skipping >2012-12-11 10:45:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-72) Autorecovering storage domains is disabled, skipping >2012-12-11 10:50:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-90) Autorecovering hosts is disabled, skipping >2012-12-11 10:50:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-90) Autorecovering storage domains is disabled, skipping >2012-12-11 10:55:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-10) Autorecovering hosts is disabled, skipping >2012-12-11 10:55:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-10) Autorecovering storage domains is disabled, skipping >2012-12-11 11:00:00,006 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-26) Autorecovering hosts is disabled, skipping >2012-12-11 11:00:00,023 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-26) Autorecovering storage domains is disabled, skipping >2012-12-11 11:05:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-46) Autorecovering hosts is disabled, skipping >2012-12-11 11:05:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-46) Autorecovering storage domains is disabled, skipping >2012-12-11 11:10:00,003 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-64) Autorecovering hosts is disabled, skipping >2012-12-11 11:10:00,003 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-64) Autorecovering storage domains is disabled, skipping >2012-12-11 11:15:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-84) Autorecovering hosts is disabled, skipping >2012-12-11 11:15:00,003 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-84) Autorecovering storage domains is disabled, skipping >2012-12-11 11:20:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-99) Autorecovering hosts is disabled, skipping >2012-12-11 11:20:00,003 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-99) Autorecovering storage domains is disabled, skipping >2012-12-11 11:25:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-17) Autorecovering hosts is disabled, skipping >2012-12-11 11:25:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-17) Autorecovering storage domains is disabled, skipping >2012-12-11 11:30:00,003 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-36) Autorecovering hosts is disabled, skipping >2012-12-11 11:30:00,003 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-36) Autorecovering storage domains is disabled, skipping >2012-12-11 11:34:25,271 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-18) DbUserCacheManager::refreshAllUserData() - entered >2012-12-11 11:35:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-58) Autorecovering hosts is disabled, skipping >2012-12-11 11:35:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-58) Autorecovering storage domains is disabled, skipping >2012-12-11 11:40:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-77) Autorecovering hosts is disabled, skipping >2012-12-11 11:40:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-77) Autorecovering storage domains is disabled, skipping >2012-12-11 11:45:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-96) Autorecovering hosts is disabled, skipping >2012-12-11 11:45:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-96) Autorecovering storage domains is disabled, skipping >2012-12-11 11:48:00,399 ERROR [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-85) Timer update runtimeinfo failed. Exception:: org.springframework.jdbc.CannotGetJdbcConnectionException: Could not get JDBC Connection; nested exception is java.sql.SQLException: javax.resource.ResourceException: IJ000453: Unable to get managed connection for java:/ENGINEDataSource > at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:80) [spring-jdbc.jar:] > at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:573) [spring-jdbc.jar:] > at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:637) [spring-jdbc.jar:] > at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:666) [spring-jdbc.jar:] > at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:706) [spring-jdbc.jar:] > at org.ovirt.engine.core.dal.dbbroker.PostgresDbEngineDialect$PostgresSimpleJdbcCall.executeCallInternal(PostgresDbEngineDialect.java:155) [engine-dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.PostgresDbEngineDialect$PostgresSimpleJdbcCall.doExecute(PostgresDbEngineDialect.java:121) [engine-dal.jar:] > at org.springframework.jdbc.core.simple.SimpleJdbcCall.execute(SimpleJdbcCall.java:181) [spring-jdbc.jar:] > at org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeImpl(SimpleJdbcCallsHandler.java:124) [engine-dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeReadAndReturnMap(SimpleJdbcCallsHandler.java:75) [engine-dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeReadList(SimpleJdbcCallsHandler.java:66) [engine-dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeRead(SimpleJdbcCallsHandler.java:58) [engine-dal.jar:] > at org.ovirt.engine.core.dao.VdsDAODbFacadeImpl.get(VdsDAODbFacadeImpl.java:37) [engine-dal.jar:] > at org.ovirt.engine.core.dao.VdsDAODbFacadeImpl.get(VdsDAODbFacadeImpl.java:32) [engine-dal.jar:] > at org.ovirt.engine.core.vdsbroker.VdsManager.OnTimer(VdsManager.java:198) [engine-vdsbroker.jar:] > at sun.reflect.GeneratedMethodAccessor7.invoke(Unknown Source) [:1.7.0_09-icedtea] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_09-icedtea] > at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_09-icedtea] > at org.ovirt.engine.core.utils.timer.JobWrapper.execute(JobWrapper.java:60) [engine-scheduler.jar:] > at org.quartz.core.JobRunShell.run(JobRunShell.java:213) [quartz.jar:] > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:557) [quartz.jar:] >Caused by: java.sql.SQLException: javax.resource.ResourceException: IJ000453: Unable to get managed connection for java:/ENGINEDataSource > at org.jboss.jca.adapters.jdbc.WrapperDataSource.getConnection(WrapperDataSource.java:137) > at org.springframework.jdbc.datasource.DataSourceUtils.doGetConnection(DataSourceUtils.java:111) [spring-jdbc.jar:] > at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:77) [spring-jdbc.jar:] > ... 20 more >Caused by: javax.resource.ResourceException: IJ000453: Unable to get managed connection for java:/ENGINEDataSource > at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.getManagedConnection(AbstractConnectionManager.java:390) > at org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl.getManagedConnection(TxConnectionManagerImpl.java:368) > at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.allocateConnection(AbstractConnectionManager.java:464) > at org.jboss.jca.adapters.jdbc.WrapperDataSource.getConnection(WrapperDataSource.java:129) > ... 22 more >Caused by: javax.resource.ResourceException: IJ000658: Unexpected throwable while trying to create a connection: null > at org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool.getConnection(SemaphoreArrayListManagedConnectionPool.java:371) > at org.jboss.jca.core.connectionmanager.pool.AbstractPool.getSimpleConnection(AbstractPool.java:397) > at org.jboss.jca.core.connectionmanager.pool.AbstractPool.getConnection(AbstractPool.java:365) > at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.getManagedConnection(AbstractConnectionManager.java:329) > ... 25 more >Caused by: javax.resource.ResourceException: Could not create connection > at org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory.getLocalManagedConnection(LocalManagedConnectionFactory.java:277) > at org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory.createManagedConnection(LocalManagedConnectionFactory.java:235) > at org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool.createConnectionEventListener(SemaphoreArrayListManagedConnectionPool.java:761) > at org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool.getConnection(SemaphoreArrayListManagedConnectionPool.java:343) > ... 28 more >Caused by: org.postgresql.util.PSQLException: Connection refused. Check that the hostname and port are correct and that the postmaster is accepting TCP/IP connections. > at org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl(ConnectionFactoryImpl.java:207) > at org.postgresql.core.ConnectionFactory.openConnection(ConnectionFactory.java:64) > at org.postgresql.jdbc2.AbstractJdbc2Connection.<init>(AbstractJdbc2Connection.java:136) > at org.postgresql.jdbc3.AbstractJdbc3Connection.<init>(AbstractJdbc3Connection.java:29) > at org.postgresql.jdbc3g.AbstractJdbc3gConnection.<init>(AbstractJdbc3gConnection.java:21) > at org.postgresql.jdbc4.AbstractJdbc4Connection.<init>(AbstractJdbc4Connection.java:31) > at org.postgresql.jdbc4.Jdbc4Connection.<init>(Jdbc4Connection.java:24) > at org.postgresql.Driver.makeConnection(Driver.java:393) > at org.postgresql.Driver.connect(Driver.java:267) > at org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory.getLocalManagedConnection(LocalManagedConnectionFactory.java:249) > ... 31 more >Caused by: java.net.ConnectException: Connection refused > at java.net.PlainSocketImpl.socketConnect(Native Method) [rt.jar:1.7.0_09-icedtea] > at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339) [rt.jar:1.7.0_09-icedtea] > at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200) [rt.jar:1.7.0_09-icedtea] > at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182) [rt.jar:1.7.0_09-icedtea] > at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:391) [rt.jar:1.7.0_09-icedtea] > at java.net.Socket.connect(Socket.java:579) [rt.jar:1.7.0_09-icedtea] > at java.net.Socket.connect(Socket.java:528) [rt.jar:1.7.0_09-icedtea] > at org.postgresql.core.PGStream.<init>(PGStream.java:60) > at org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl(ConnectionFactoryImpl.java:101) > ... 40 more > >2012-12-11 11:48:02,523 ERROR [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-86) Timer update runtimeinfo failed. Exception:: org.springframework.jdbc.CannotGetJdbcConnectionException: Could not get JDBC Connection; nested exception is java.sql.SQLException: javax.resource.ResourceException: IJ000453: Unable to get managed connection for java:/ENGINEDataSource > at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:80) [spring-jdbc.jar:] > at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:573) [spring-jdbc.jar:] > at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:637) [spring-jdbc.jar:] > at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:666) [spring-jdbc.jar:] > at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:706) [spring-jdbc.jar:] > at org.ovirt.engine.core.dal.dbbroker.PostgresDbEngineDialect$PostgresSimpleJdbcCall.executeCallInternal(PostgresDbEngineDialect.java:155) [engine-dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.PostgresDbEngineDialect$PostgresSimpleJdbcCall.doExecute(PostgresDbEngineDialect.java:121) [engine-dal.jar:] > at org.springframework.jdbc.core.simple.SimpleJdbcCall.execute(SimpleJdbcCall.java:181) [spring-jdbc.jar:] > at org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeImpl(SimpleJdbcCallsHandler.java:124) [engine-dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeReadAndReturnMap(SimpleJdbcCallsHandler.java:75) [engine-dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeReadList(SimpleJdbcCallsHandler.java:66) [engine-dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeRead(SimpleJdbcCallsHandler.java:58) [engine-dal.jar:] > at org.ovirt.engine.core.dao.VdsDAODbFacadeImpl.get(VdsDAODbFacadeImpl.java:37) [engine-dal.jar:] > at org.ovirt.engine.core.dao.VdsDAODbFacadeImpl.get(VdsDAODbFacadeImpl.java:32) [engine-dal.jar:] > at org.ovirt.engine.core.vdsbroker.VdsManager.OnTimer(VdsManager.java:198) [engine-vdsbroker.jar:] > at sun.reflect.GeneratedMethodAccessor7.invoke(Unknown Source) [:1.7.0_09-icedtea] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_09-icedtea] > at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_09-icedtea] > at org.ovirt.engine.core.utils.timer.JobWrapper.execute(JobWrapper.java:60) [engine-scheduler.jar:] > at org.quartz.core.JobRunShell.run(JobRunShell.java:213) [quartz.jar:] > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:557) [quartz.jar:] >Caused by: java.sql.SQLException: javax.resource.ResourceException: IJ000453: Unable to get managed connection for java:/ENGINEDataSource > at org.jboss.jca.adapters.jdbc.WrapperDataSource.getConnection(WrapperDataSource.java:137) > at org.springframework.jdbc.datasource.DataSourceUtils.doGetConnection(DataSourceUtils.java:111) [spring-jdbc.jar:] > at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:77) [spring-jdbc.jar:] > ... 20 more >Caused by: javax.resource.ResourceException: IJ000453: Unable to get managed connection for java:/ENGINEDataSource > at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.getManagedConnection(AbstractConnectionManager.java:390) > at org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl.getManagedConnection(TxConnectionManagerImpl.java:368) > at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.allocateConnection(AbstractConnectionManager.java:464) > at org.jboss.jca.adapters.jdbc.WrapperDataSource.getConnection(WrapperDataSource.java:129) > ... 22 more >Caused by: javax.resource.ResourceException: IJ000658: Unexpected throwable while trying to create a connection: null > at org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool.getConnection(SemaphoreArrayListManagedConnectionPool.java:371) > at org.jboss.jca.core.connectionmanager.pool.AbstractPool.getSimpleConnection(AbstractPool.java:397) > at org.jboss.jca.core.connectionmanager.pool.AbstractPool.getConnection(AbstractPool.java:365) > at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.getManagedConnection(AbstractConnectionManager.java:329) > ... 25 more >Caused by: javax.resource.ResourceException: Could not create connection > at org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory.getLocalManagedConnection(LocalManagedConnectionFactory.java:277) > at org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory.createManagedConnection(LocalManagedConnectionFactory.java:235) > at org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool.createConnectionEventListener(SemaphoreArrayListManagedConnectionPool.java:761) > at org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool.getConnection(SemaphoreArrayListManagedConnectionPool.java:343) > ... 28 more >Caused by: org.postgresql.util.PSQLException: FATAL: password authentication failed for user "engine" > at org.postgresql.core.v3.ConnectionFactoryImpl.doAuthentication(ConnectionFactoryImpl.java:398) > at org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl(ConnectionFactoryImpl.java:173) > at org.postgresql.core.ConnectionFactory.openConnection(ConnectionFactory.java:64) > at org.postgresql.jdbc2.AbstractJdbc2Connection.<init>(AbstractJdbc2Connection.java:136) > at org.postgresql.jdbc3.AbstractJdbc3Connection.<init>(AbstractJdbc3Connection.java:29) > at org.postgresql.jdbc3g.AbstractJdbc3gConnection.<init>(AbstractJdbc3gConnection.java:21) > at org.postgresql.jdbc4.AbstractJdbc4Connection.<init>(AbstractJdbc4Connection.java:31) > at org.postgresql.jdbc4.Jdbc4Connection.<init>(Jdbc4Connection.java:24) > at org.postgresql.Driver.makeConnection(Driver.java:393) > at org.postgresql.Driver.connect(Driver.java:267) > at org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory.getLocalManagedConnection(LocalManagedConnectionFactory.java:249) > ... 31 more > >2012-12-11 11:50:58,225 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) Start time: 12/11/12 11:50 AM >2012-12-11 11:50:58,513 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: CbcCheckOnVdsChange >2012-12-11 11:50:58,547 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: CAEngineKey >2012-12-11 11:50:58,548 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: ENGINEEARLib >2012-12-11 11:50:58,614 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: MinimalETLVersion >2012-12-11 11:50:59,231 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: ScriptsPath >2012-12-11 11:50:59,240 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: SQLServerI18NPrefix >2012-12-11 11:51:00,199 ERROR [org.ovirt.engine.core.engineencryptutils.EncryptionUtils] (MSC service thread 1-1) Failed to decrypt Data must start with zero >2012-12-11 11:51:00,200 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-1) Failed to decrypt value for property TruststorePass will be used encrypted value >2012-12-11 11:51:00,240 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-1) Could not parse option AutoRecoveryAllowedTypes value. >2012-12-11 11:51:00,566 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) VDSBrokerFrontend: 12/11/12 11:51 AM >2012-12-11 11:51:00,583 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) CpuFlagsManager: 12/11/12 11:51 AM >2012-12-11 11:51:00,584 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Setting audit clean up manager to run at: 35 35 3 * * ? >2012-12-11 11:51:00,665 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) AuditLogCleanupManager: 12/11/12 11:51 AM >2012-12-11 11:51:00,697 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-1) TagsDirector initialization >2012-12-11 11:51:00,773 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-1) Tag root added to tree >2012-12-11 11:51:00,806 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) TagsDirector: 12/11/12 11:51 AM >2012-12-11 11:51:00,825 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) IsoDomainListSyncronizer: 12/11/12 11:51 AM >2012-12-11 11:51:00,876 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) InitHandlers: 12/11/12 11:51 AM >2012-12-11 11:51:00,951 WARN [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-1) Code VDS_FENCING_OPERATION_FAILED appears more then once in string table. >2012-12-11 11:51:00,959 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) ErrorTranslator: 12/11/12 11:51 AM >2012-12-11 11:51:00,959 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) VdsErrorTranslator: 12/11/12 11:51 AM >2012-12-11 11:51:00,964 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) Mark uncompleted jobs as UNKNOWN: 12/11/12 11:51 AM >2012-12-11 11:51:01,075 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) JobRepositoryCleanupManager: 12/11/12 11:51 AM >2012-12-11 11:51:01,082 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) AutoRecoveryManager: 12/11/12 11:51 AM >2012-12-11 11:51:01,095 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) ExecutionMessageDirector: 12/11/12 11:51 AM >2012-12-11 11:51:01,183 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-2) InitResourceManager: 12/11/12 11:51 AM >2012-12-11 11:51:01,190 INFO [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-1) UsersDomainsCacheManager: 12/11/12 11:51 AM >2012-12-11 11:51:01,194 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-2) ResourceManager::ResourceManager::Entered >2012-12-11 11:51:01,315 INFO [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-1) DbUserCacheManager: 12/11/12 11:51 AM >2012-12-11 11:51:01,494 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-1) DbUserCacheManager::refreshAllUserData() - entered >2012-12-11 11:51:01,617 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (MSC service thread 1-2) AsyncTaskManager: Initialization of AsyncTaskManager completed successfully. >2012-12-11 11:51:01,618 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-2) AsyncTaskManager: 12/11/12 11:51 AM >2012-12-11 11:51:01,657 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-2) VdsLoadBalancer: 12/11/12 11:51 AM >2012-12-11 11:51:01,675 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (pool-3-thread-1) MacPoolManager started: 12/11/12 11:51 AM >2012-12-11 11:51:01,758 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-2) Init VM Custom Properties utilities: 12/11/12 11:51 AM >2012-12-11 11:51:01,826 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (pool-3-thread-1) MacPoolManager finished: 12/11/12 11:51 AM >2012-12-11 11:51:35,598 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) Start time: 12/11/12 11:51 AM >2012-12-11 11:51:35,740 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: CbcCheckOnVdsChange >2012-12-11 11:51:35,757 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: CAEngineKey >2012-12-11 11:51:35,757 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: ENGINEEARLib >2012-12-11 11:51:35,780 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: MinimalETLVersion >2012-12-11 11:51:36,078 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: ScriptsPath >2012-12-11 11:51:36,084 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: SQLServerI18NPrefix >2012-12-11 11:51:36,514 ERROR [org.ovirt.engine.core.engineencryptutils.EncryptionUtils] (MSC service thread 1-1) Failed to decrypt Data must start with zero >2012-12-11 11:51:36,515 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-1) Failed to decrypt value for property TruststorePass will be used encrypted value >2012-12-11 11:51:36,536 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-1) Could not parse option AutoRecoveryAllowedTypes value. >2012-12-11 11:51:36,752 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) VDSBrokerFrontend: 12/11/12 11:51 AM >2012-12-11 11:51:36,762 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) CpuFlagsManager: 12/11/12 11:51 AM >2012-12-11 11:51:36,763 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Setting audit clean up manager to run at: 35 35 3 * * ? >2012-12-11 11:51:36,794 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) AuditLogCleanupManager: 12/11/12 11:51 AM >2012-12-11 11:51:36,798 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-1) TagsDirector initialization >2012-12-11 11:51:36,833 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-1) Tag root added to tree >2012-12-11 11:51:36,850 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) TagsDirector: 12/11/12 11:51 AM >2012-12-11 11:51:36,863 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) IsoDomainListSyncronizer: 12/11/12 11:51 AM >2012-12-11 11:51:36,900 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) InitHandlers: 12/11/12 11:51 AM >2012-12-11 11:51:36,924 WARN [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-1) Code VDS_FENCING_OPERATION_FAILED appears more then once in string table. >2012-12-11 11:51:36,926 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) ErrorTranslator: 12/11/12 11:51 AM >2012-12-11 11:51:36,926 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) VdsErrorTranslator: 12/11/12 11:51 AM >2012-12-11 11:51:36,927 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) Mark uncompleted jobs as UNKNOWN: 12/11/12 11:51 AM >2012-12-11 11:51:36,991 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) JobRepositoryCleanupManager: 12/11/12 11:51 AM >2012-12-11 11:51:36,992 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) AutoRecoveryManager: 12/11/12 11:51 AM >2012-12-11 11:51:36,999 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) ExecutionMessageDirector: 12/11/12 11:51 AM >2012-12-11 11:51:37,033 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-2) InitResourceManager: 12/11/12 11:51 AM >2012-12-11 11:51:37,034 INFO [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-1) UsersDomainsCacheManager: 12/11/12 11:51 AM >2012-12-11 11:51:37,042 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-2) ResourceManager::ResourceManager::Entered >2012-12-11 11:51:37,117 INFO [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-1) DbUserCacheManager: 12/11/12 11:51 AM >2012-12-11 11:51:37,197 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-1) DbUserCacheManager::refreshAllUserData() - entered >2012-12-11 11:51:37,260 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (MSC service thread 1-2) AsyncTaskManager: Initialization of AsyncTaskManager completed successfully. >2012-12-11 11:51:37,261 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-2) AsyncTaskManager: 12/11/12 11:51 AM >2012-12-11 11:51:37,268 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-2) VdsLoadBalancer: 12/11/12 11:51 AM >2012-12-11 11:51:37,277 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (pool-3-thread-1) MacPoolManager started: 12/11/12 11:51 AM >2012-12-11 11:51:37,311 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-2) Init VM Custom Properties utilities: 12/11/12 11:51 AM >2012-12-11 11:51:37,334 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (pool-3-thread-1) MacPoolManager finished: 12/11/12 11:51 AM >2012-12-11 11:52:01,105 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-3) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:01,113 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-3) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:01,220 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: UNASSIGNED not exist in string table >2012-12-11 11:52:01,220 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: USER_RUN_UNLOCK_ENTITY_SCRIPT not exist in string table >2012-12-11 11:52:01,226 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: VDS_NETWORK_MTU_DIFFER_FROM_LOGICAL_NETWORK not exist in string table >2012-12-11 11:52:01,227 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: STORAGE_ACTIVATE_ASYNC not exist in string table >2012-12-11 11:52:01,227 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: DWH_STOPPED not exist in string table >2012-12-11 11:52:01,227 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: DWH_STARTED not exist in string table >2012-12-11 11:52:01,228 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: DWH_ERROR not exist in string table >2012-12-11 11:52:01,236 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: USER_ATTACH_DISK_TO_VM not have severity. Assumed Normal >2012-12-11 11:52:01,236 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: USER_FAILED_ATTACH_DISK_TO_VM not have severity. Assumed Normal >2012-12-11 11:52:01,236 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: USER_DETACH_DISK_FROM_VM not have severity. Assumed Normal >2012-12-11 11:52:01,237 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: USER_FAILED_DETACH_DISK_FROM_VM not have severity. Assumed Normal >2012-12-11 11:52:01,237 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: USER_RUN_UNLOCK_ENTITY_SCRIPT not have severity. Assumed Normal >2012-12-11 11:52:01,238 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: VM_MIGRATION_START_SYSTEM_INITIATED not have severity. Assumed Normal >2012-12-11 11:52:01,238 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: VDS_NETWORK_MTU_DIFFER_FROM_LOGICAL_NETWORK not have severity. Assumed Normal >2012-12-11 11:52:01,305 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-3) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:03,572 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-4) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:03,574 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-4) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:03,636 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-4) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:04,966 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-4) calling GetConfigurationValueQuery (SupportedClusterLevels) with null version, using default general for version >2012-12-11 11:52:04,990 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-4) calling GetConfigurationValueQuery (VdcVersion) with null version, using default general for version >2012-12-11 11:52:15,994 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-5) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:15,996 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-5) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:16,023 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-5) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:16,119 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-1) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:16,122 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-1) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:16,137 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-1) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:16,855 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:16,861 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:16,880 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:17,506 INFO [org.ovirt.engine.core.bll.storage.AddEmptyStoragePoolCommand] (ajp--127.0.0.1-8702-6) [7fa4fa43] Running command: AddEmptyStoragePoolCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-12-11 11:52:17,590 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:17,592 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:17,608 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-2) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:17,779 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-3) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:17,781 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-3) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:17,796 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-3) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:18,193 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-4) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:18,198 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-4) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:18,227 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-4) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:18,458 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-7) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:18,468 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-7) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:18,488 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-7) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:18,594 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-8) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:18,596 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-8) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:18,615 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-8) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:18,775 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-9) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:18,779 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-9) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:18,806 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-9) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:18,903 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-10) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:18,906 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-10) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:18,921 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-10) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:19,091 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-11) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:19,093 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-11) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:19,109 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-11) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:19,200 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-12) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:19,203 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-12) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:19,216 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-12) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:19,381 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-13) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:19,384 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-13) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:19,437 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-13) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:19,542 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-14) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:19,544 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-14) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:19,565 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-14) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:19,673 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-15) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:19,695 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-15) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:19,704 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-15) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:19,764 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-16) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:19,766 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-16) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:19,779 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-16) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:19,901 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-17) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:19,905 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-17) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:19,932 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-17) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:20,024 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-18) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:20,026 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-18) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:20,045 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-18) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:20,212 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-19) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:20,214 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-19) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:20,230 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-19) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:20,309 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-20) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:20,311 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-20) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:20,321 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-20) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:20,467 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-21) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:20,469 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-21) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:20,485 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-21) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:20,563 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-22) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:20,565 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-22) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:20,581 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-22) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:20,694 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-23) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:20,696 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-23) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:20,708 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-23) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:20,767 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-24) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:20,769 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-24) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:20,783 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-24) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:20,907 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-25) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:20,909 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-25) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:20,928 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-25) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:21,022 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-26) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:21,032 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-26) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:21,055 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-26) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:21,139 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:21,141 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:21,151 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-27) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:21,209 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-28) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:21,211 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-28) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:21,222 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-28) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:21,617 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-29) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:21,626 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-29) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:21,661 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-29) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:21,763 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-30) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:21,772 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-30) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:21,796 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-30) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:22,431 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-31) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:22,433 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-31) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:22,449 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-31) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:23,013 INFO [org.ovirt.engine.core.bll.AddVdsGroupCommand] (ajp--127.0.0.1-8702-31) [b727e47] Running command: AddVdsGroupCommand internal: false. Entities affected : ID: d32a8696-910b-4222-a364-1c00b5303fbb Type: StoragePool >2012-12-11 11:52:23,104 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:23,106 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:23,121 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-32) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:23,649 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:23,651 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:23,682 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-33) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:24,233 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:24,234 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:24,251 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-34) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:24,989 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-35) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:24,993 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-35) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:25,039 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-35) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:25,159 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-36) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:25,165 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-36) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:25,183 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-36) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:26,201 INFO [org.ovirt.engine.core.bll.AddVdsCommand] (ajp--127.0.0.1-8702-36) [667b3ec4] Running command: AddVdsCommand internal: false. Entities affected : ID: 4a39265e-b3a1-424b-b0a2-0b5f123ce104 Type: VdsGroups >2012-12-11 11:52:26,354 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp--127.0.0.1-8702-36) [e5fbd27] Running command: AddVdsSpmIdCommand internal: true. Entities affected : ID: e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f Type: VDS >2012-12-11 11:52:26,378 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp--127.0.0.1-8702-36) [e5fbd27] Lock freed to object EngineLock [exclusiveLocks= key: d32a8696-910b-4222-a364-1c00b5303fbb value: REGISTER_VDS >, sharedLocks= ] >2012-12-11 11:52:26,391 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp--127.0.0.1-8702-36) [e5fbd27] Cannot get vdsManager for vdsid=e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f >2012-12-11 11:52:26,409 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp--127.0.0.1-8702-36) [e5fbd27] START, RemoveVdsVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f), log id: 2d1b0164 >2012-12-11 11:52:26,414 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp--127.0.0.1-8702-36) [e5fbd27] Cannot get vdsManager for vdsid=e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f >2012-12-11 11:52:26,416 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp--127.0.0.1-8702-36) [e5fbd27] FINISH, RemoveVdsVDSCommand, log id: 2d1b0164 >2012-12-11 11:52:26,424 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp--127.0.0.1-8702-36) [e5fbd27] Cannot get vdsManager for vdsid=e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f >2012-12-11 11:52:26,439 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-36) [e5fbd27] START, AddVdsVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f), log id: 38ee5fbc >2012-12-11 11:52:26,442 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-36) [e5fbd27] AddVds - entered , starting logic to add VDS e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f >2012-12-11 11:52:26,456 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-36) [e5fbd27] AddVds - VDS e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f was added, will try to add it to the resource manager >2012-12-11 11:52:26,469 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp--127.0.0.1-8702-36) [e5fbd27] Eneterd VdsManager:constructor >2012-12-11 11:52:26,470 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp--127.0.0.1-8702-36) [e5fbd27] vdsBroker(10.35.148.46,54,321) >2012-12-11 11:52:26,632 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp--127.0.0.1-8702-36) [e5fbd27] ResourceManager::AddVds - VDS e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f was added to the Resource Manager >2012-12-11 11:52:26,636 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-36) [e5fbd27] FINISH, AddVdsVDSCommand, log id: 38ee5fbc >2012-12-11 11:52:26,740 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-3-thread-40) [6942baeb] Running command: InstallVdsCommand internal: true. Entities affected : ID: e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f Type: VDS >2012-12-11 11:52:26,760 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-3-thread-40) [6942baeb] Before Installation host e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, cinteg04.ci.lab.tlv.redhat.com >2012-12-11 11:52:26,807 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-5) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:26,811 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-5) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:26,836 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-5) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:26,870 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Loaded file "/usr/share/ovirt-engine/conf/engine.conf.defaults". >2012-12-11 11:52:26,873 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Loaded file "/etc/sysconfig/ovirt-engine". >2012-12-11 11:52:26,878 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_DB_URL" is "jdbc:postgresql://localhost:5432/engine". >2012-12-11 11:52:26,887 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_PROPERTIES" is "". >2012-12-11 11:52:26,890 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_PKI" is "/etc/pki/ovirt-engine". >2012-12-11 11:52:26,904 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_PERM_MIN" is "256m". >2012-12-11 11:52:26,910 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "JBOSS_HOME" is "/usr/share/jboss-as". >2012-12-11 11:52:26,912 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_DB_USER" is "engine". >2012-12-11 11:52:26,922 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_LOG" is "/var/log/ovirt-engine". >2012-12-11 11:52:26,932 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_PROXY_HTTP_PORT" is "80". >2012-12-11 11:52:26,939 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_STOP_TIME" is "10". >2012-12-11 11:52:26,952 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_FQDN" is "vm-18-155.eng.lab.tlv.redhat.com". >2012-12-11 11:52:26,954 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_HEAP_MAX" is "1g". >2012-12-11 11:52:26,956 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_DB_MIN_CONNECTIONS" is "1". >2012-12-11 11:52:26,962 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_LOCK" is "/var/lock/ovirt-engine". >2012-12-11 11:52:26,966 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_STOP_INTERVAL" is "1". >2012-12-11 11:52:26,967 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_PROXY_ENABLED" is "true". >2012-12-11 11:52:26,975 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_DB_DRIVER" is "org.postgresql.Driver". >2012-12-11 11:52:26,986 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_APPS" is "engine.ear". >2012-12-11 11:52:26,991 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_DB_PASSWORD" is "64c5fd2979a86168". >2012-12-11 11:52:27,013 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_ETC" is "/etc/ovirt-engine". >2012-12-11 11:52:27,026 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_VAR" is "/var/lib/ovirt-engine". >2012-12-11 11:52:27,030 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_DB_MAX_CONNECTIONS" is "100". >2012-12-11 11:52:27,031 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_HTTP_PORT" is "8700". >2012-12-11 11:52:27,039 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_AJP_PORT" is "8702". >2012-12-11 11:52:27,046 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_PERM_MAX" is "256m". >2012-12-11 11:52:27,049 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_VERBOSE_GC" is "false". >2012-12-11 11:52:27,057 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_HTTPS_PORT" is "8701". >2012-12-11 11:52:27,066 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_DEBUG_ADDRESS" is "". >2012-12-11 11:52:27,068 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_CACHE" is "/var/cache/ovirt-engine". >2012-12-11 11:52:27,069 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_NOFILE" is "65535". >2012-12-11 11:52:27,076 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_PROXY_HTTPS_PORT" is "443". >2012-12-11 11:52:27,078 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_PID" is "/var/run/ovirt-engine.pid". >2012-12-11 11:52:27,086 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_USR" is "/usr/share/ovirt-engine". >2012-12-11 11:52:27,091 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_HTTP_ENABLED" is "false". >2012-12-11 11:52:27,094 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_GROUP" is "ovirt". >2012-12-11 11:52:27,100 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "JAVA_HOME" is "/usr/lib/jvm/jre-1.7.0-openjdk.x86_64". >2012-12-11 11:52:27,106 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_HTTPS_ENABLED" is "false". >2012-12-11 11:52:27,107 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_DB_SSL" is "false". >2012-12-11 11:52:27,109 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_TMP" is "/var/tmp/ovirt-engine". >2012-12-11 11:52:27,115 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_HEAP_MIN" is "1g". >2012-12-11 11:52:27,119 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_AJP_ENABLED" is "true". >2012-12-11 11:52:27,125 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_USER" is "ovirt". >2012-12-11 11:52:27,315 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-3-thread-40) [6942baeb] Installation 10.35.148.46: Connected to host 10.35.148.46 with SSH key fingerprint: f0:fd:e4:79:b6:20:c3:63:71:22:c2:29:e0:38:66:ee >2012-12-11 11:52:27,376 INFO [org.ovirt.engine.core.bll.VdsDeploy] (pool-3-thread-40) [6942baeb] Installation of 10.35.148.46. Executing command via SSH umask 0077; MYTMP="$(mktemp -t ovirt-XXXXXXXXXX)"; trap "chmod -R u+rwX \"${MYTMP}\" > /dev/null 2>&1; rm -fr \"${MYTMP}\" > /dev/null 2>&1" 0; rm -fr "${MYTMP}" && mkdir "${MYTMP}" && python -c "import sys, tarfile; tarfile.open(fileobj=sys.stdin, mode='r|').extractall(path='${MYTMP}')" && "${MYTMP}"/setup DIALOG/dialect=str:machine DIALOG/customization=bool:True < /var/cache/ovirt-engine/ovirt-host-deploy.tar >2012-12-11 11:52:27,426 INFO [org.ovirt.engine.core.utils.ssh.SSHDialog] (pool-3-thread-40) SSH execute root@10.35.148.46 'umask 0077; MYTMP="$(mktemp -t ovirt-XXXXXXXXXX)"; trap "chmod -R u+rwX \"${MYTMP}\" > /dev/null 2>&1; rm -fr \"${MYTMP}\" > /dev/null 2>&1" 0; rm -fr "${MYTMP}" && mkdir "${MYTMP}" && python -c "import sys, tarfile; tarfile.open(fileobj=sys.stdin, mode='r|').extractall(path='${MYTMP}')" && "${MYTMP}"/setup DIALOG/dialect=str:machine DIALOG/customization=bool:True' >2012-12-11 11:52:27,434 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:27,436 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:27,475 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:27,621 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:27,623 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:27,649 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-2) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:28,220 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Initializing >2012-12-11 11:52:28,273 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Environment setup >2012-12-11 11:52:28,307 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Installation packages setup >2012-12-11 11:52:28,636 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-23) Initializing Host: cinteg04.ci.lab.tlv.redhat.com >2012-12-11 11:52:33,007 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Programs detection >2012-12-11 11:52:33,026 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Environment customization >2012-12-11 11:52:33,051 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Logs at host located at: '/tmp/ovirt-host-deploy-20121211115227.log' >2012-12-11 11:52:33,139 INFO [org.ovirt.engine.core.bll.VdsDeploy] (VdsDeploy) Host 10.35.148.46 reports unique id e3dcd4be-1162-4eec-9d36-c120696b3902 >2012-12-11 11:52:33,181 INFO [org.ovirt.engine.core.bll.VdsDeploy] (VdsDeploy) Assigning unique id e3dcd4be-1162-4eec-9d36-c120696b3902 to Host 10.35.148.46 >2012-12-11 11:52:33,254 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Enfocing host reboot >2012-12-11 11:52:33,269 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Setup validation >2012-12-11 11:52:33,285 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Hardware supports virtualization >2012-12-11 11:52:33,299 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Transaction setup >2012-12-11 11:52:33,316 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Package installation >2012-12-11 11:52:34,603 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Misc configuration >2012-12-11 11:52:35,157 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Enrolling certificate >2012-12-11 11:52:36,261 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Transaction commit >2012-12-11 11:52:36,279 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Closing up >2012-12-11 11:52:36,340 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Reboot scheduled >2012-12-11 11:52:36,353 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Pre-termination >2012-12-11 11:52:36,385 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Reboot scheduled >2012-12-11 11:52:36,399 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Retrieving installation logs to: '/var/log/ovirt-engine/host-deploy/ovirt-20121211115236-10.35.148.46.log' >2012-12-11 11:52:36,577 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Termination >2012-12-11 11:52:36,677 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-3-thread-40) [6942baeb] After Installation host cinteg04.ci.lab.tlv.redhat.com, VDS >2012-12-11 11:52:36,685 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-3-thread-44) [6942baeb] Waiting 150 seconds, for server to finish reboot process. >2012-12-11 11:52:37,783 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-15) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:37,787 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-15) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:37,804 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-15) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:47,969 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-26) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:47,972 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-26) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:47,991 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-26) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:52:58,138 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Checking if user admin@internal is an admin, result true >2012-12-11 11:52:58,141 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Running command: LoginUserCommand internal: false. >2012-12-11 11:52:58,157 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-32) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:53:08,340 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Checking if user admin@internal is an admin, result true >2012-12-11 11:53:08,345 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Running command: LoginUserCommand internal: false. >2012-12-11 11:53:08,384 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-33) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:53:18,523 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Checking if user admin@internal is an admin, result true >2012-12-11 11:53:18,526 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Running command: LoginUserCommand internal: false. >2012-12-11 11:53:18,539 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-34) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:53:28,711 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-35) Checking if user admin@internal is an admin, result true >2012-12-11 11:53:28,715 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-35) Running command: LoginUserCommand internal: false. >2012-12-11 11:53:28,730 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-35) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:53:38,815 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-36) Checking if user admin@internal is an admin, result true >2012-12-11 11:53:38,823 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-36) Running command: LoginUserCommand internal: false. >2012-12-11 11:53:38,844 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-36) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:53:48,997 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-5) Checking if user admin@internal is an admin, result true >2012-12-11 11:53:49,000 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-5) Running command: LoginUserCommand internal: false. >2012-12-11 11:53:49,026 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-5) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:53:59,139 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Checking if user admin@internal is an admin, result true >2012-12-11 11:53:59,142 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Running command: LoginUserCommand internal: false. >2012-12-11 11:53:59,159 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:54:09,307 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Checking if user admin@internal is an admin, result true >2012-12-11 11:54:09,311 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Running command: LoginUserCommand internal: false. >2012-12-11 11:54:09,339 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-2) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:54:19,481 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-15) Checking if user admin@internal is an admin, result true >2012-12-11 11:54:19,483 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-15) Running command: LoginUserCommand internal: false. >2012-12-11 11:54:19,499 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-15) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:54:29,645 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-26) Checking if user admin@internal is an admin, result true >2012-12-11 11:54:29,652 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-26) Running command: LoginUserCommand internal: false. >2012-12-11 11:54:29,677 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-26) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:54:39,790 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Checking if user admin@internal is an admin, result true >2012-12-11 11:54:39,800 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Running command: LoginUserCommand internal: false. >2012-12-11 11:54:39,826 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-32) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:54:49,976 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Checking if user admin@internal is an admin, result true >2012-12-11 11:54:49,984 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Running command: LoginUserCommand internal: false. >2012-12-11 11:54:50,002 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-33) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:00,004 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-67) Autorecovering hosts is disabled, skipping >2012-12-11 11:55:00,008 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-67) Autorecovering storage domains is disabled, skipping >2012-12-11 11:55:00,123 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:00,128 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:00,159 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-34) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:06,694 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-3-thread-44) [6942baeb] START, SetVdsStatusVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, status=NonResponsive, nonOperationalReason=NONE), log id: ffb1ab4 >2012-12-11 11:55:06,715 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-3-thread-44) [6942baeb] FINISH, SetVdsStatusVDSCommand, log id: ffb1ab4 >2012-12-11 11:55:08,735 INFO [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (QuartzScheduler_Worker-72) [43073edb] Running command: InitVdsOnUpCommand internal: true. >2012-12-11 11:55:08,758 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (QuartzScheduler_Worker-72) [43073edb] No string for UNASSIGNED type. Use default Log >2012-12-11 11:55:08,774 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-3-thread-44) [43073edb] MultipleActionsRunner of type MigrateVmToServer invoked with no actions >2012-12-11 11:55:08,844 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (QuartzScheduler_Worker-72) [69a80de6] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f Type: VDS >2012-12-11 11:55:08,860 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (QuartzScheduler_Worker-72) [259f9e81] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f Type: VDS >2012-12-11 11:55:10,328 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-35) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:10,332 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-35) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:10,355 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-35) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:10,781 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-36) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:10,784 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-36) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:10,810 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-36) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:11,036 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-5) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:11,039 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-5) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:11,058 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-5) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:11,133 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:11,139 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:11,158 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:11,312 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:11,314 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:11,331 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-2) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:11,394 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-15) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:11,397 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-15) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:11,411 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-15) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:11,548 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-26) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:11,550 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-26) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:11,566 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-26) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:11,640 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:11,652 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:11,667 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-32) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:11,784 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:11,786 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:11,799 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-33) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:11,882 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:11,883 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:11,896 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-34) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:12,411 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-31) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:12,419 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-31) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:12,439 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-31) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:13,389 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-30) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:13,392 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-30) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:13,437 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-30) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:13,578 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-29) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:13,583 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-29) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:13,601 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-29) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:14,409 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-28) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:14,412 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-28) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:14,436 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-28) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:14,630 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:14,632 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:14,646 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-27) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:14,728 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-25) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:14,730 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-25) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:14,746 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-25) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:14,851 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-24) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:14,853 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-24) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:14,868 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-24) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:15,436 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-23) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:15,439 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-23) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:15,467 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-23) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:16,110 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-22) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:16,112 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-22) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:16,143 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-22) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:16,640 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--127.0.0.1-8702-22) [768cc3a9] START, ValidateStorageServerConnectionVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.35.148.12:/vol/vfilers/nfs01/vm-18-155_nfs_2012_12_11_11_52_10_163873, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 4a1ccad6 >2012-12-11 11:55:16,692 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--127.0.0.1-8702-22) [768cc3a9] FINISH, ValidateStorageServerConnectionVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 4a1ccad6 >2012-12-11 11:55:16,702 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (ajp--127.0.0.1-8702-22) [768cc3a9] Running command: AddStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-12-11 11:55:16,716 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-22) [768cc3a9] START, ConnectStorageServerVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.35.148.12:/vol/vfilers/nfs01/vm-18-155_nfs_2012_12_11_11_52_10_163873, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 3ba8c53e >2012-12-11 11:55:17,021 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-22) [768cc3a9] FINISH, ConnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 3ba8c53e >2012-12-11 11:55:17,057 INFO [org.ovirt.engine.core.bll.storage.GetExistingStorageDomainListQuery] (ajp--127.0.0.1-8702-22) START, GetExistingStorageDomainListQuery(org.ovirt.engine.core.common.queries.GetExistingStorageDomainListParameters@6581b41f), log id: 26331c38 >2012-12-11 11:55:17,077 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetStorageDomainsListVDSCommand] (ajp--127.0.0.1-8702-22) START, HSMGetStorageDomainsListVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId=00000000-0000-0000-0000-000000000000, storageType=NFS, storageDomainType=Data, path=10.35.148.12:/vol/vfilers/nfs01/vm-18-155_nfs_2012_12_11_11_52_10_163873), log id: 395bbb79 >2012-12-11 11:55:19,263 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetStorageDomainsListVDSCommand] (ajp--127.0.0.1-8702-22) FINISH, HSMGetStorageDomainsListVDSCommand, return: [], log id: 395bbb79 >2012-12-11 11:55:19,267 INFO [org.ovirt.engine.core.bll.storage.GetExistingStorageDomainListQuery] (ajp--127.0.0.1-8702-22) FINISH, GetExistingStorageDomainListQuery, log id: 26331c38 >2012-12-11 11:55:19,365 INFO [org.ovirt.engine.core.bll.storage.AddNFSStorageDomainCommand] (ajp--127.0.0.1-8702-22) [5484b8d3] Running command: AddNFSStorageDomainCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-12-11 11:55:19,426 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (ajp--127.0.0.1-8702-22) [5484b8d3] START, CreateStorageDomainVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storageDomain=org.ovirt.engine.core.common.businessentities.storage_domain_static@a9cf2910, args=10.35.148.12:/vol/vfilers/nfs01/vm-18-155_nfs_2012_12_11_11_52_10_163873), log id: 54b0338a >2012-12-11 11:55:19,583 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (ajp--127.0.0.1-8702-22) [5484b8d3] FINISH, CreateStorageDomainVDSCommand, log id: 54b0338a >2012-12-11 11:55:19,615 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (ajp--127.0.0.1-8702-22) [5484b8d3] START, GetStorageDomainStatsVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storageDomainId=4c83c7d8-2e16-4607-a09c-abd89369b0c0), log id: 38f30d2b >2012-12-11 11:55:19,762 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (ajp--127.0.0.1-8702-22) [5484b8d3] FINISH, GetStorageDomainStatsVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage_domains@f1e42c73, log id: 38f30d2b >2012-12-11 11:55:19,972 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-21) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:19,973 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-21) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:19,986 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-21) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:20,394 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-20) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:20,396 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-20) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:20,410 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-20) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:20,465 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-19) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:20,467 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-19) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:20,478 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-19) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:20,603 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-35) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:20,605 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-35) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:20,621 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-35) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:20,660 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-36) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:20,662 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-36) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:20,675 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-36) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:20,759 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-5) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:20,761 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-5) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:20,771 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-5) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:20,828 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:20,829 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:20,839 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:20,903 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--127.0.0.1-8702-6) [3d75faaf] START, ValidateStorageServerConnectionVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.35.148.12:/vol/vfilers/nfs01/vm-18-155_nfs_2012_12_11_11_52_11_520428, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 124d26aa >2012-12-11 11:55:20,943 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--127.0.0.1-8702-6) [3d75faaf] FINISH, ValidateStorageServerConnectionVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 124d26aa >2012-12-11 11:55:20,966 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (ajp--127.0.0.1-8702-6) [3d75faaf] Running command: AddStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-12-11 11:55:20,983 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-6) [3d75faaf] START, ConnectStorageServerVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.35.148.12:/vol/vfilers/nfs01/vm-18-155_nfs_2012_12_11_11_52_11_520428, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 173bb19f >2012-12-11 11:55:21,092 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-6) [3d75faaf] FINISH, ConnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 173bb19f >2012-12-11 11:55:21,115 INFO [org.ovirt.engine.core.bll.storage.GetExistingStorageDomainListQuery] (ajp--127.0.0.1-8702-6) START, GetExistingStorageDomainListQuery(org.ovirt.engine.core.common.queries.GetExistingStorageDomainListParameters@6f175bcb), log id: 13713bf5 >2012-12-11 11:55:21,123 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetStorageDomainsListVDSCommand] (ajp--127.0.0.1-8702-6) START, HSMGetStorageDomainsListVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId=00000000-0000-0000-0000-000000000000, storageType=NFS, storageDomainType=Data, path=10.35.148.12:/vol/vfilers/nfs01/vm-18-155_nfs_2012_12_11_11_52_11_520428), log id: 5585490f >2012-12-11 11:55:23,111 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetStorageDomainsListVDSCommand] (ajp--127.0.0.1-8702-6) FINISH, HSMGetStorageDomainsListVDSCommand, return: [], log id: 5585490f >2012-12-11 11:55:23,114 INFO [org.ovirt.engine.core.bll.storage.GetExistingStorageDomainListQuery] (ajp--127.0.0.1-8702-6) FINISH, GetExistingStorageDomainListQuery, log id: 13713bf5 >2012-12-11 11:55:23,170 INFO [org.ovirt.engine.core.bll.storage.AddNFSStorageDomainCommand] (ajp--127.0.0.1-8702-6) [50f4c9e0] Running command: AddNFSStorageDomainCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-12-11 11:55:23,197 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (ajp--127.0.0.1-8702-6) [50f4c9e0] START, CreateStorageDomainVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storageDomain=org.ovirt.engine.core.common.businessentities.storage_domain_static@d24202e9, args=10.35.148.12:/vol/vfilers/nfs01/vm-18-155_nfs_2012_12_11_11_52_11_520428), log id: 6d5b70d3 >2012-12-11 11:55:23,346 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (ajp--127.0.0.1-8702-6) [50f4c9e0] FINISH, CreateStorageDomainVDSCommand, log id: 6d5b70d3 >2012-12-11 11:55:23,353 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (ajp--127.0.0.1-8702-6) [50f4c9e0] START, GetStorageDomainStatsVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storageDomainId=ebb36e55-5d97-4ca1-abc4-cb98c6c82b4f), log id: 44bc2ddf >2012-12-11 11:55:23,435 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (ajp--127.0.0.1-8702-6) [50f4c9e0] FINISH, GetStorageDomainStatsVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage_domains@4a102373, log id: 44bc2ddf >2012-12-11 11:55:23,596 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-26) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:23,600 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-26) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:23,611 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-26) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:23,910 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:23,912 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:23,968 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-32) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:24,159 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:24,161 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:24,177 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-33) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:24,277 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:24,278 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:24,299 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-34) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:24,397 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-31) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:24,399 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-31) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:24,413 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-31) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:24,453 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-30) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:24,455 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-30) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:24,470 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-30) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:24,570 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-29) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:24,572 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-29) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:24,581 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-29) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:24,680 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--127.0.0.1-8702-29) [44efa94c] START, ValidateStorageServerConnectionVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.35.16.27:/volumes/shafan/vm-18-155_nfs_20121211115212316558, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 65b700a2 >2012-12-11 11:55:24,727 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--127.0.0.1-8702-29) [44efa94c] FINISH, ValidateStorageServerConnectionVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 65b700a2 >2012-12-11 11:55:24,735 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (ajp--127.0.0.1-8702-29) [44efa94c] Running command: AddStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-12-11 11:55:24,745 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-29) [44efa94c] START, ConnectStorageServerVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.35.16.27:/volumes/shafan/vm-18-155_nfs_20121211115212316558, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 36d39242 >2012-12-11 11:55:24,948 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-29) [44efa94c] FINISH, ConnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 36d39242 >2012-12-11 11:55:24,963 INFO [org.ovirt.engine.core.bll.storage.GetExistingStorageDomainListQuery] (ajp--127.0.0.1-8702-29) START, GetExistingStorageDomainListQuery(org.ovirt.engine.core.common.queries.GetExistingStorageDomainListParameters@6ef62b35), log id: 49090440 >2012-12-11 11:55:24,969 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetStorageDomainsListVDSCommand] (ajp--127.0.0.1-8702-29) START, HSMGetStorageDomainsListVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId=00000000-0000-0000-0000-000000000000, storageType=NFS, storageDomainType=ISO, path=10.35.16.27:/volumes/shafan/vm-18-155_nfs_20121211115212316558), log id: 38a6ae0e >2012-12-11 11:55:26,916 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetStorageDomainsListVDSCommand] (ajp--127.0.0.1-8702-29) FINISH, HSMGetStorageDomainsListVDSCommand, return: [], log id: 38a6ae0e >2012-12-11 11:55:26,920 INFO [org.ovirt.engine.core.bll.storage.GetExistingStorageDomainListQuery] (ajp--127.0.0.1-8702-29) FINISH, GetExistingStorageDomainListQuery, log id: 49090440 >2012-12-11 11:55:26,975 INFO [org.ovirt.engine.core.bll.storage.AddNFSStorageDomainCommand] (ajp--127.0.0.1-8702-29) [2ed75a67] Running command: AddNFSStorageDomainCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-12-11 11:55:27,003 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (ajp--127.0.0.1-8702-29) [2ed75a67] START, CreateStorageDomainVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storageDomain=org.ovirt.engine.core.common.businessentities.storage_domain_static@5cce7ae1, args=10.35.16.27:/volumes/shafan/vm-18-155_nfs_20121211115212316558), log id: 50d4caaa >2012-12-11 11:55:27,208 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (ajp--127.0.0.1-8702-29) [2ed75a67] FINISH, CreateStorageDomainVDSCommand, log id: 50d4caaa >2012-12-11 11:55:27,211 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (ajp--127.0.0.1-8702-29) [2ed75a67] START, GetStorageDomainStatsVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storageDomainId=30067796-d127-493d-8dae-e9e81613e08c), log id: 4688e156 >2012-12-11 11:55:27,300 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (ajp--127.0.0.1-8702-29) [2ed75a67] FINISH, GetStorageDomainStatsVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage_domains@a6edbdf3, log id: 4688e156 >2012-12-11 11:55:27,415 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-28) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:27,418 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-28) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:27,440 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-28) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:27,811 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:27,812 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:27,830 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-27) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:27,877 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-25) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:27,879 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-25) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:27,891 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-25) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:28,083 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-24) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:28,086 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-24) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:28,100 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-24) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:28,211 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-23) Checking if user admin@internal is an admin, result true >2012-12-11 11:55:28,217 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-23) Running command: LoginUserCommand internal: false. >2012-12-11 11:55:28,233 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-23) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:55:28,334 INFO [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] (ajp--127.0.0.1-8702-23) [6bbf17fe] Running command: AttachStorageDomainToPoolCommand internal: false. Entities affected : ID: 4c83c7d8-2e16-4607-a09c-abd89369b0c0 Type: Storage >2012-12-11 11:55:28,353 INFO [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (ajp--127.0.0.1-8702-23) [14e7c038] Lock Acquired to object EngineLock [exclusiveLocks= key: d32a8696-910b-4222-a364-1c00b5303fbb value: POOL >, sharedLocks= ] >2012-12-11 11:55:28,363 INFO [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (ajp--127.0.0.1-8702-23) [14e7c038] Running command: AddStoragePoolWithStoragesCommand internal: true. Entities affected : ID: d32a8696-910b-4222-a364-1c00b5303fbb Type: StoragePool >2012-12-11 11:55:28,477 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--127.0.0.1-8702-23) [76a4d8f5] START, ValidateStorageServerConnectionVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 4203bf6c-8e6c-4ea9-a4c5-01c0090036c5, connection: 10.35.148.12:/vol/vfilers/nfs01/vm-18-155_nfs_2012_12_11_11_52_10_163873, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 1effa89e >2012-12-11 11:55:28,519 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--127.0.0.1-8702-23) [76a4d8f5] FINISH, ValidateStorageServerConnectionVDSCommand, return: {4203bf6c-8e6c-4ea9-a4c5-01c0090036c5=0}, log id: 1effa89e >2012-12-11 11:55:28,522 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (ajp--127.0.0.1-8702-23) [76a4d8f5] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-12-11 11:55:28,540 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-23) [76a4d8f5] START, ConnectStorageServerVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 4203bf6c-8e6c-4ea9-a4c5-01c0090036c5, connection: 10.35.148.12:/vol/vfilers/nfs01/vm-18-155_nfs_2012_12_11_11_52_10_163873, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 77c93dcb >2012-12-11 11:55:28,587 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-23) [76a4d8f5] FINISH, ConnectStorageServerVDSCommand, return: {4203bf6c-8e6c-4ea9-a4c5-01c0090036c5=0}, log id: 77c93dcb >2012-12-11 11:55:28,597 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStoragePoolVDSCommand] (ajp--127.0.0.1-8702-23) [76a4d8f5] START, CreateStoragePoolVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId=d32a8696-910b-4222-a364-1c00b5303fbb, storageType=NFS, storagePoolName=nfsStorageTest, masterDomainId=4c83c7d8-2e16-4607-a09c-abd89369b0c0, domainsIdList=[4c83c7d8-2e16-4607-a09c-abd89369b0c0], masterVersion=1), log id: 88cda1a >2012-12-11 11:55:55,366 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStoragePoolVDSCommand] (ajp--127.0.0.1-8702-23) [76a4d8f5] FINISH, CreateStoragePoolVDSCommand, log id: 88cda1a >2012-12-11 11:55:55,373 INFO [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (ajp--127.0.0.1-8702-23) [76a4d8f5] Lock freed to object EngineLock [exclusiveLocks= key: d32a8696-910b-4222-a364-1c00b5303fbb value: POOL >, sharedLocks= ] >2012-12-11 11:55:55,388 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-23) [2b5b073b] Lock Acquired to object EngineLock [exclusiveLocks= key: 4c83c7d8-2e16-4607-a09c-abd89369b0c0 value: STORAGE >, sharedLocks= ] >2012-12-11 11:55:55,400 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-23) [2b5b073b] Running command: ActivateStorageDomainCommand internal: true. Entities affected : ID: 4c83c7d8-2e16-4607-a09c-abd89369b0c0 Type: Storage >2012-12-11 11:55:55,430 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-23) [2b5b073b] Lock freed to object EngineLock [exclusiveLocks= key: 4c83c7d8-2e16-4607-a09c-abd89369b0c0 value: STORAGE >, sharedLocks= ] >2012-12-11 11:55:55,440 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-23) [2b5b073b] ActivateStorage Domain. Before Connect all hosts to pool. Time:12/11/12 11:55 AM >2012-12-11 11:55:55,459 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (pool-3-thread-44) [44f55e2b] START, ValidateStorageServerConnectionVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 4203bf6c-8e6c-4ea9-a4c5-01c0090036c5, connection: 10.35.148.12:/vol/vfilers/nfs01/vm-18-155_nfs_2012_12_11_11_52_10_163873, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: ebd8bf6 >2012-12-11 11:55:55,530 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (pool-3-thread-44) [44f55e2b] FINISH, ValidateStorageServerConnectionVDSCommand, return: {4203bf6c-8e6c-4ea9-a4c5-01c0090036c5=0}, log id: ebd8bf6 >2012-12-11 11:55:55,535 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (pool-3-thread-44) [44f55e2b] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-12-11 11:55:55,549 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-3-thread-44) [44f55e2b] START, ConnectStorageServerVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 4203bf6c-8e6c-4ea9-a4c5-01c0090036c5, connection: 10.35.148.12:/vol/vfilers/nfs01/vm-18-155_nfs_2012_12_11_11_52_10_163873, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 2b0c3e60 >2012-12-11 11:55:55,620 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-3-thread-44) [44f55e2b] FINISH, ConnectStorageServerVDSCommand, return: {4203bf6c-8e6c-4ea9-a4c5-01c0090036c5=0}, log id: 2b0c3e60 >2012-12-11 11:55:55,624 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (ajp--127.0.0.1-8702-23) [2b5b073b] START, ActivateStorageDomainVDSCommand( storagePoolId = d32a8696-910b-4222-a364-1c00b5303fbb, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 4c83c7d8-2e16-4607-a09c-abd89369b0c0), log id: 5e7b5a5c >2012-12-11 11:55:55,651 INFO [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (ajp--127.0.0.1-8702-23) [5657df2c] Running command: SetStoragePoolStatusCommand internal: true. Entities affected : ID: d32a8696-910b-4222-a364-1c00b5303fbb Type: StoragePool >2012-12-11 11:55:55,730 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (ajp--127.0.0.1-8702-23) [5657df2c] hostFromVds::selectedVds - cinteg04.ci.lab.tlv.redhat.com, spmStatus Unknown_Pool, storage pool nfsStorageTest >2012-12-11 11:55:55,741 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (ajp--127.0.0.1-8702-23) [5657df2c] START, ConnectStoragePoolVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = d32a8696-910b-4222-a364-1c00b5303fbb, vds_spm_id = 1, masterDomainId = 4c83c7d8-2e16-4607-a09c-abd89369b0c0, masterVersion = 1), log id: 2255c10b >2012-12-11 11:56:18,743 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (ajp--127.0.0.1-8702-23) [5657df2c] FINISH, ConnectStoragePoolVDSCommand, log id: 2255c10b >2012-12-11 11:56:18,783 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (ajp--127.0.0.1-8702-23) [5657df2c] hostFromVds::Connected host to pool - selectedVds - cinteg04.ci.lab.tlv.redhat.com, spmStatus Free, storage pool nfsStorageTest >2012-12-11 11:56:18,798 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (ajp--127.0.0.1-8702-23) [5657df2c] starting spm on vds cinteg04.ci.lab.tlv.redhat.com, storage pool nfsStorageTest, prevId -1, LVER -1 >2012-12-11 11:56:18,805 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp--127.0.0.1-8702-23) [5657df2c] START, SpmStartVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = d32a8696-910b-4222-a364-1c00b5303fbb, prevId=-1, prevLVER=-1, storagePoolFormatType=V3, recoveryMode=Manual, SCSIFencing=false), log id: 1a3619ad >2012-12-11 11:56:18,871 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp--127.0.0.1-8702-23) [5657df2c] spmStart polling started: taskId = 8f52b0b2-2600-4c40-933e-248e745b05b0 >2012-12-11 11:56:19,933 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp--127.0.0.1-8702-23) [5657df2c] spmStart polling ended: taskId = 8f52b0b2-2600-4c40-933e-248e745b05b0 task status = finished >2012-12-11 11:56:19,973 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp--127.0.0.1-8702-23) [5657df2c] spmStart polling ended, spm status: SPM >2012-12-11 11:56:19,984 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (ajp--127.0.0.1-8702-23) [5657df2c] START, HSMClearTaskVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, taskId=8f52b0b2-2600-4c40-933e-248e745b05b0), log id: 6125f991 >2012-12-11 11:56:20,056 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (ajp--127.0.0.1-8702-23) [5657df2c] FINISH, HSMClearTaskVDSCommand, log id: 6125f991 >2012-12-11 11:56:20,063 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp--127.0.0.1-8702-23) [5657df2c] FINISH, SpmStartVDSCommand, return: org.ovirt.engine.core.common.businessentities.SpmStatusResult@50b7c3a3, log id: 1a3619ad >2012-12-11 11:56:20,075 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (ajp--127.0.0.1-8702-23) [5657df2c] Initialize Irs proxy from vds: 10.35.148.46 >2012-12-11 11:56:20,170 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (ajp--127.0.0.1-8702-23) [5657df2c] FINISH, ActivateStorageDomainVDSCommand, log id: 5e7b5a5c >2012-12-11 11:56:20,175 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-23) [5657df2c] ActivateStorage Domain. After Connect all hosts to pool. Time:12/11/12 11:56 AM >2012-12-11 11:56:20,187 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (pool-3-thread-44) [5657df2c] START, ConnectStoragePoolVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = d32a8696-910b-4222-a364-1c00b5303fbb, vds_spm_id = 1, masterDomainId = 4c83c7d8-2e16-4607-a09c-abd89369b0c0, masterVersion = 1), log id: 27884e93 >2012-12-11 11:56:20,219 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (pool-3-thread-44) [5657df2c] FINISH, ConnectStoragePoolVDSCommand, log id: 27884e93 >2012-12-11 11:56:20,256 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-23) [5657df2c] ActivateStorage Domain. After change storage pool status in vds. Time:12/11/12 11:56 AM >2012-12-11 11:56:20,319 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Checking if user admin@internal is an admin, result true >2012-12-11 11:56:20,326 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Running command: LoginUserCommand internal: false. >2012-12-11 11:56:20,336 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-32) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:56:20,425 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Checking if user admin@internal is an admin, result true >2012-12-11 11:56:20,427 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Running command: LoginUserCommand internal: false. >2012-12-11 11:56:20,438 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-33) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:56:20,540 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Checking if user admin@internal is an admin, result true >2012-12-11 11:56:20,542 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Running command: LoginUserCommand internal: false. >2012-12-11 11:56:20,554 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-34) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:56:20,858 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-31) Checking if user admin@internal is an admin, result true >2012-12-11 11:56:20,864 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-31) Running command: LoginUserCommand internal: false. >2012-12-11 11:56:20,886 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-31) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:56:21,032 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-30) Checking if user admin@internal is an admin, result true >2012-12-11 11:56:21,038 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-30) Running command: LoginUserCommand internal: false. >2012-12-11 11:56:21,056 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-30) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:56:21,115 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-29) Checking if user admin@internal is an admin, result true >2012-12-11 11:56:21,117 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-29) Running command: LoginUserCommand internal: false. >2012-12-11 11:56:21,131 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-29) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:56:21,238 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-28) Checking if user admin@internal is an admin, result true >2012-12-11 11:56:21,240 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-28) Running command: LoginUserCommand internal: false. >2012-12-11 11:56:21,249 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-28) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:56:21,288 INFO [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] (ajp--127.0.0.1-8702-28) [3ee5dc4f] Running command: AttachStorageDomainToPoolCommand internal: false. Entities affected : ID: ebb36e55-5d97-4ca1-abc4-cb98c6c82b4f Type: Storage >2012-12-11 11:56:21,317 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (pool-3-thread-50) [572b4065] START, ValidateStorageServerConnectionVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 52b83eb6-c76d-46a7-ab4a-d871f74ab84e, connection: 10.35.148.12:/vol/vfilers/nfs01/vm-18-155_nfs_2012_12_11_11_52_11_520428, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 6f0625f8 >2012-12-11 11:56:21,362 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (pool-3-thread-50) [572b4065] FINISH, ValidateStorageServerConnectionVDSCommand, return: {52b83eb6-c76d-46a7-ab4a-d871f74ab84e=0}, log id: 6f0625f8 >2012-12-11 11:56:21,365 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (pool-3-thread-50) [572b4065] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-12-11 11:56:21,373 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-3-thread-50) [572b4065] START, ConnectStorageServerVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 52b83eb6-c76d-46a7-ab4a-d871f74ab84e, connection: 10.35.148.12:/vol/vfilers/nfs01/vm-18-155_nfs_2012_12_11_11_52_11_520428, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 71a62f3d >2012-12-11 11:56:21,435 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-3-thread-50) [572b4065] FINISH, ConnectStorageServerVDSCommand, return: {52b83eb6-c76d-46a7-ab4a-d871f74ab84e=0}, log id: 71a62f3d >2012-12-11 11:56:21,441 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (ajp--127.0.0.1-8702-28) [3ee5dc4f] START, AttachStorageDomainVDSCommand( storagePoolId = d32a8696-910b-4222-a364-1c00b5303fbb, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = ebb36e55-5d97-4ca1-abc4-cb98c6c82b4f), log id: 404966e1 >2012-12-11 11:56:23,404 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp--127.0.0.1-8702-28) [3ee5dc4f] Failed in AttachStorageDomainVDS method >2012-12-11 11:56:23,407 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp--127.0.0.1-8702-28) [3ee5dc4f] Error code AcquireLockFailure and error message IRSGenericException: IRSErrorException: Failed to AttachStorageDomainVDS, error = Cannot obtain lock: "id=ebb36e55-5d97-4ca1-abc4-cb98c6c82b4f, rc=28, out=Cannot acquire cluster lock, err=(28, 'Sanlock resource not acquired', 'No space left on device')" >2012-12-11 11:56:23,422 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (ajp--127.0.0.1-8702-28) [3ee5dc4f] IrsBroker::Failed::AttachStorageDomainVDS due to: IRSErrorException: IRSGenericException: IRSErrorException: Failed to AttachStorageDomainVDS, error = Cannot obtain lock: "id=ebb36e55-5d97-4ca1-abc4-cb98c6c82b4f, rc=28, out=Cannot acquire cluster lock, err=(28, 'Sanlock resource not acquired', 'No space left on device')" >2012-12-11 11:56:23,437 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (ajp--127.0.0.1-8702-28) [3ee5dc4f] FINISH, AttachStorageDomainVDSCommand, log id: 404966e1 >2012-12-11 11:56:23,440 ERROR [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] (ajp--127.0.0.1-8702-28) [3ee5dc4f] Command org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand throw Vdc Bll exception. With error message VdcBLLException: org.ovirt.engine.core.vdsbroker.irsbroker.IRSErrorException: IRSGenericException: IRSErrorException: Failed to AttachStorageDomainVDS, error = Cannot obtain lock: "id=ebb36e55-5d97-4ca1-abc4-cb98c6c82b4f, rc=28, out=Cannot acquire cluster lock, err=(28, 'Sanlock resource not acquired', 'No space left on device')" >2012-12-11 11:56:23,494 INFO [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] (ajp--127.0.0.1-8702-28) [3ee5dc4f] Command [id=45dbf219-c2b7-4fb9-8561-3d5a8530a66d]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.storage_pool_iso_map; snapshot: storagePoolId = d32a8696-910b-4222-a364-1c00b5303fbb, storageId = ebb36e55-5d97-4ca1-abc4-cb98c6c82b4f. >2012-12-11 11:56:23,561 ERROR [org.ovirt.engine.api.restapi.resource.AbstractBackendResource] (ajp--127.0.0.1-8702-28) Operation Failed: [Could not obtain lock] >2012-12-11 11:56:24,012 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Checking if user admin@internal is an admin, result true >2012-12-11 11:56:24,014 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Running command: LoginUserCommand internal: false. >2012-12-11 11:56:24,025 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-27) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:56:24,113 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-25) Checking if user admin@internal is an admin, result true >2012-12-11 11:56:24,116 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-25) Running command: LoginUserCommand internal: false. >2012-12-11 11:56:24,124 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-25) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 11:56:24,424 WARN [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (QuartzScheduler_Worker-46) domain 4c83c7d8-2e16-4607-a09c-abd89369b0c0:nfsStorageTest_data_domain_0 in problem. vds: cinteg04.ci.lab.tlv.redhat.com >2012-12-11 11:57:39,202 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-29) ResourceManager::refreshVdsRunTimeInfo::Failed to refresh VDS , vds = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f : cinteg04.ci.lab.tlv.redhat.com, VDS Network Error, continuing. >java.net.ConnectException: Connection refused >2012-12-11 11:57:40,977 INFO [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (QuartzScheduler_Worker-30) [4a652843] Running command: SetStoragePoolStatusCommand internal: true. Entities affected : ID: d32a8696-910b-4222-a364-1c00b5303fbb Type: StoragePool >2012-12-11 11:57:41,004 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (QuartzScheduler_Worker-30) [4a652843] IrsBroker::Failed::GetStoragePoolInfoVDS due to: ConnectException: Connection refused >2012-12-11 11:57:41,217 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-31) ResourceManager::refreshVdsRunTimeInfo::Failed to refresh VDS , vds = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f : cinteg04.ci.lab.tlv.redhat.com, VDS Network Error, continuing. >java.net.ConnectException: Connection refused >2012-12-11 11:57:44,303 INFO [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (QuartzScheduler_Worker-33) [2d3a1459] Running command: InitVdsOnUpCommand internal: true. >2012-12-11 11:57:44,336 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (QuartzScheduler_Worker-33) [351b5e46] START, ValidateStorageServerConnectionVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = d32a8696-910b-4222-a364-1c00b5303fbb, storageType = NFS, connectionList = [{ id: 4203bf6c-8e6c-4ea9-a4c5-01c0090036c5, connection: 10.35.148.12:/vol/vfilers/nfs01/vm-18-155_nfs_2012_12_11_11_52_10_163873, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 413c0913 >2012-12-11 11:57:44,378 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (QuartzScheduler_Worker-33) [351b5e46] FINISH, ValidateStorageServerConnectionVDSCommand, return: {4203bf6c-8e6c-4ea9-a4c5-01c0090036c5=0}, log id: 413c0913 >2012-12-11 11:57:44,380 INFO [org.ovirt.engine.core.bll.storage.ConnectHostToStoragePoolServersCommand] (QuartzScheduler_Worker-33) [351b5e46] Running command: ConnectHostToStoragePoolServersCommand internal: true. Entities affected : ID: d32a8696-910b-4222-a364-1c00b5303fbb Type: StoragePool >2012-12-11 11:57:44,386 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (QuartzScheduler_Worker-33) [351b5e46] START, ConnectStorageServerVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = d32a8696-910b-4222-a364-1c00b5303fbb, storageType = NFS, connectionList = [{ id: 4203bf6c-8e6c-4ea9-a4c5-01c0090036c5, connection: 10.35.148.12:/vol/vfilers/nfs01/vm-18-155_nfs_2012_12_11_11_52_10_163873, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 22abc73 >2012-12-11 11:57:44,452 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (QuartzScheduler_Worker-33) [351b5e46] FINISH, ConnectStorageServerVDSCommand, return: {4203bf6c-8e6c-4ea9-a4c5-01c0090036c5=0}, log id: 22abc73 >2012-12-11 11:57:44,453 INFO [org.ovirt.engine.core.bll.storage.ConnectHostToStoragePoolServersCommand] (QuartzScheduler_Worker-33) [351b5e46] Host cinteg04.ci.lab.tlv.redhat.com storage connection was succeeded >2012-12-11 11:57:44,464 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (QuartzScheduler_Worker-33) [351b5e46] START, ConnectStoragePoolVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = d32a8696-910b-4222-a364-1c00b5303fbb, vds_spm_id = 1, masterDomainId = 4c83c7d8-2e16-4607-a09c-abd89369b0c0, masterVersion = 1), log id: 4a93d0ea >2012-12-11 11:57:46,606 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (QuartzScheduler_Worker-33) [351b5e46] Command org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand return value > Class Name: org.ovirt.engine.core.vdsbroker.vdsbroker.StatusOnlyReturnForXmlRpc >mStatus Class Name: org.ovirt.engine.core.vdsbroker.vdsbroker.StatusForXmlRpc >mCode 304 >mMessage Cannot find master domain: 'spUUID=d32a8696-910b-4222-a364-1c00b5303fbb, msdUUID=4c83c7d8-2e16-4607-a09c-abd89369b0c0' > > >2012-12-11 11:57:46,609 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (QuartzScheduler_Worker-33) [351b5e46] HostName = cinteg04.ci.lab.tlv.redhat.com >2012-12-11 11:57:46,610 ERROR [org.ovirt.engine.core.vdsbroker.VDSCommandBase] (QuartzScheduler_Worker-33) [351b5e46] Command ConnectStoragePoolVDS execution failed. Exception: IRSNoMasterDomainException: IRSGenericException: IRSErrorException: IRSNoMasterDomainException: Cannot find master domain: 'spUUID=d32a8696-910b-4222-a364-1c00b5303fbb, msdUUID=4c83c7d8-2e16-4607-a09c-abd89369b0c0' >2012-12-11 11:57:46,613 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (QuartzScheduler_Worker-33) [351b5e46] FINISH, ConnectStoragePoolVDSCommand, log id: 4a93d0ea >2012-12-11 11:57:46,614 ERROR [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (QuartzScheduler_Worker-33) [351b5e46] Could not connect host cinteg04.ci.lab.tlv.redhat.com to pool nfsStorageTest >2012-12-11 11:57:46,646 INFO [org.ovirt.engine.core.bll.SetNonOperationalVdsCommand] (QuartzScheduler_Worker-33) [22c74276] Running command: SetNonOperationalVdsCommand internal: true. Entities affected : ID: e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f Type: VDS >2012-12-11 11:57:46,654 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (QuartzScheduler_Worker-33) [22c74276] START, SetVdsStatusVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, status=NonOperational, nonOperationalReason=STORAGE_DOMAIN_UNREACHABLE), log id: 5f09ab9f >2012-12-11 11:57:46,657 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (QuartzScheduler_Worker-33) [22c74276] SetVdsStatusVDSCommand::VSD cinteg04.ci.lab.tlv.redhat.com is spm and moved from up calling ResetIrs. >2012-12-11 11:57:46,658 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ResetIrsVDSCommand] (QuartzScheduler_Worker-33) [22c74276] START, ResetIrsVDSCommand( storagePoolId = d32a8696-910b-4222-a364-1c00b5303fbb, ignoreFailoverLimit = false, compatabilityVersion = null, vdsId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, ignoreStopFailed = false), log id: 35770fe8 >2012-12-11 11:57:46,667 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand] (QuartzScheduler_Worker-33) [22c74276] START, SpmStopVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f, storagePoolId = d32a8696-910b-4222-a364-1c00b5303fbb), log id: 4989c3a6 >2012-12-11 11:57:46,714 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand] (QuartzScheduler_Worker-33) [22c74276] SpmStopVDSCommand::Stopping SPM on vds cinteg04.ci.lab.tlv.redhat.com, pool id d32a8696-910b-4222-a364-1c00b5303fbb >2012-12-11 11:57:46,781 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand] (QuartzScheduler_Worker-33) [22c74276] FINISH, SpmStopVDSCommand, log id: 4989c3a6 >2012-12-11 11:57:46,785 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ResetIrsVDSCommand] (QuartzScheduler_Worker-33) [22c74276] FINISH, ResetIrsVDSCommand, log id: 35770fe8 >2012-12-11 11:57:46,786 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (QuartzScheduler_Worker-33) [22c74276] FINISH, SetVdsStatusVDSCommand, log id: 5f09ab9f >2012-12-11 11:57:46,863 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (QuartzScheduler_Worker-33) [22c74276] Try to add duplicate values with same name. Type: VDS_SET_NONOPERATIONAL_DOMAIN. Value: storagepoolname >2012-12-11 11:57:46,906 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (QuartzScheduler_Worker-33) [39259411] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f Type: VDS >2012-12-11 11:57:46,914 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (QuartzScheduler_Worker-33) [3debf6d6] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f Type: VDS >2012-12-11 11:57:46,920 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-33) [3debf6d6] Host e4df2d3f-14e7-4cbb-b182-10f0bb0ebe5f : cinteg04.ci.lab.tlv.redhat.com is already in NonOperational status. SetNonOperationalVds command is skipped. >2012-12-11 12:00:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-74) Autorecovering hosts is disabled, skipping >2012-12-11 12:00:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-74) Autorecovering storage domains is disabled, skipping >2012-12-11 12:01:24,443 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (QuartzScheduler_Worker-62) starting ProcessDomainRecovery for domain 4c83c7d8-2e16-4607-a09c-abd89369b0c0:nfsStorageTest_data_domain_0 >2012-12-11 12:01:24,506 WARN [org.ovirt.engine.core.bll.storage.ReconstructMasterDomainCommand] (QuartzScheduler_Worker-62) [31eb34d6] CanDoAction of action ReconstructMasterDomain failed. Reasons:VAR__ACTION__RECONSTRUCT_MASTER,VAR__TYPE__STORAGE__DOMAIN,ACTION_TYPE_FAILED_NO_VDS_IN_POOL >2012-12-11 12:05:00,004 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-93) Autorecovering hosts is disabled, skipping >2012-12-11 12:05:00,006 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-93) Autorecovering storage domains is disabled, skipping >2012-12-11 12:10:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-11) Autorecovering hosts is disabled, skipping >2012-12-11 12:10:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-11) Autorecovering storage domains is disabled, skipping >2012-12-11 12:15:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-30) [4a652843] Autorecovering hosts is disabled, skipping >2012-12-11 12:15:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-30) [4a652843] Autorecovering storage domains is disabled, skipping >2012-12-11 12:20:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-47) Autorecovering hosts is disabled, skipping >2012-12-11 12:20:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-47) Autorecovering storage domains is disabled, skipping >2012-12-11 12:22:02,626 ERROR [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-80) Timer update runtimeinfo failed. Exception:: org.springframework.jdbc.CannotGetJdbcConnectionException: Could not get JDBC Connection; nested exception is java.sql.SQLException: javax.resource.ResourceException: IJ000453: Unable to get managed connection for java:/ENGINEDataSource > at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:80) [spring-jdbc.jar:] > at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:573) [spring-jdbc.jar:] > at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:637) [spring-jdbc.jar:] > at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:666) [spring-jdbc.jar:] > at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:706) [spring-jdbc.jar:] > at org.ovirt.engine.core.dal.dbbroker.PostgresDbEngineDialect$PostgresSimpleJdbcCall.executeCallInternal(PostgresDbEngineDialect.java:155) [engine-dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.PostgresDbEngineDialect$PostgresSimpleJdbcCall.doExecute(PostgresDbEngineDialect.java:121) [engine-dal.jar:] > at org.springframework.jdbc.core.simple.SimpleJdbcCall.execute(SimpleJdbcCall.java:181) [spring-jdbc.jar:] > at org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeImpl(SimpleJdbcCallsHandler.java:124) [engine-dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeReadAndReturnMap(SimpleJdbcCallsHandler.java:75) [engine-dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeReadList(SimpleJdbcCallsHandler.java:66) [engine-dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeRead(SimpleJdbcCallsHandler.java:58) [engine-dal.jar:] > at org.ovirt.engine.core.dao.VdsDAODbFacadeImpl.get(VdsDAODbFacadeImpl.java:37) [engine-dal.jar:] > at org.ovirt.engine.core.dao.VdsDAODbFacadeImpl.get(VdsDAODbFacadeImpl.java:32) [engine-dal.jar:] > at org.ovirt.engine.core.vdsbroker.VdsManager.OnTimer(VdsManager.java:198) [engine-vdsbroker.jar:] > at sun.reflect.GeneratedMethodAccessor84.invoke(Unknown Source) [:1.7.0_09-icedtea] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_09-icedtea] > at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_09-icedtea] > at org.ovirt.engine.core.utils.timer.JobWrapper.execute(JobWrapper.java:60) [engine-scheduler.jar:] > at org.quartz.core.JobRunShell.run(JobRunShell.java:213) [quartz.jar:] > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:557) [quartz.jar:] >Caused by: java.sql.SQLException: javax.resource.ResourceException: IJ000453: Unable to get managed connection for java:/ENGINEDataSource > at org.jboss.jca.adapters.jdbc.WrapperDataSource.getConnection(WrapperDataSource.java:137) > at org.springframework.jdbc.datasource.DataSourceUtils.doGetConnection(DataSourceUtils.java:111) [spring-jdbc.jar:] > at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:77) [spring-jdbc.jar:] > ... 20 more >Caused by: javax.resource.ResourceException: IJ000453: Unable to get managed connection for java:/ENGINEDataSource > at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.getManagedConnection(AbstractConnectionManager.java:390) > at org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl.getManagedConnection(TxConnectionManagerImpl.java:368) > at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.allocateConnection(AbstractConnectionManager.java:464) > at org.jboss.jca.adapters.jdbc.WrapperDataSource.getConnection(WrapperDataSource.java:129) > ... 22 more >Caused by: javax.resource.ResourceException: IJ000658: Unexpected throwable while trying to create a connection: null > at org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool.getConnection(SemaphoreArrayListManagedConnectionPool.java:371) > at org.jboss.jca.core.connectionmanager.pool.AbstractPool.getSimpleConnection(AbstractPool.java:397) > at org.jboss.jca.core.connectionmanager.pool.AbstractPool.getConnection(AbstractPool.java:365) > at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.getManagedConnection(AbstractConnectionManager.java:329) > ... 25 more >Caused by: javax.resource.ResourceException: Could not create connection > at org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory.getLocalManagedConnection(LocalManagedConnectionFactory.java:277) > at org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory.createManagedConnection(LocalManagedConnectionFactory.java:235) > at org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool.createConnectionEventListener(SemaphoreArrayListManagedConnectionPool.java:761) > at org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool.getConnection(SemaphoreArrayListManagedConnectionPool.java:343) > ... 28 more >Caused by: org.postgresql.util.PSQLException: Connection refused. Check that the hostname and port are correct and that the postmaster is accepting TCP/IP connections. > at org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl(ConnectionFactoryImpl.java:207) > at org.postgresql.core.ConnectionFactory.openConnection(ConnectionFactory.java:64) > at org.postgresql.jdbc2.AbstractJdbc2Connection.<init>(AbstractJdbc2Connection.java:136) > at org.postgresql.jdbc3.AbstractJdbc3Connection.<init>(AbstractJdbc3Connection.java:29) > at org.postgresql.jdbc3g.AbstractJdbc3gConnection.<init>(AbstractJdbc3gConnection.java:21) > at org.postgresql.jdbc4.AbstractJdbc4Connection.<init>(AbstractJdbc4Connection.java:31) > at org.postgresql.jdbc4.Jdbc4Connection.<init>(Jdbc4Connection.java:24) > at org.postgresql.Driver.makeConnection(Driver.java:393) > at org.postgresql.Driver.connect(Driver.java:267) > at org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory.getLocalManagedConnection(LocalManagedConnectionFactory.java:249) > ... 31 more >Caused by: java.net.ConnectException: Connection refused > at java.net.PlainSocketImpl.socketConnect(Native Method) [rt.jar:1.7.0_09-icedtea] > at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339) [rt.jar:1.7.0_09-icedtea] > at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200) [rt.jar:1.7.0_09-icedtea] > at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182) [rt.jar:1.7.0_09-icedtea] > at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:391) [rt.jar:1.7.0_09-icedtea] > at java.net.Socket.connect(Socket.java:579) [rt.jar:1.7.0_09-icedtea] > at java.net.Socket.connect(Socket.java:528) [rt.jar:1.7.0_09-icedtea] > at org.postgresql.core.PGStream.<init>(PGStream.java:60) > at org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl(ConnectionFactoryImpl.java:101) > ... 40 more > >2012-12-11 12:42:56,400 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Start time: 12/11/12 12:42 PM >2012-12-11 12:42:56,723 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: CbcCheckOnVdsChange >2012-12-11 12:42:56,755 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: CAEngineKey >2012-12-11 12:42:56,755 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: ENGINEEARLib >2012-12-11 12:42:56,801 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: MinimalETLVersion >2012-12-11 12:42:57,411 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: ScriptsPath >2012-12-11 12:42:57,427 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: SQLServerI18NPrefix >2012-12-11 12:42:58,216 ERROR [org.ovirt.engine.core.engineencryptutils.EncryptionUtils] (MSC service thread 1-2) Failed to decrypt Data must start with zero >2012-12-11 12:42:58,217 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-2) Failed to decrypt value for property TruststorePass will be used encrypted value >2012-12-11 12:42:58,288 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-2) Could not parse option AutoRecoveryAllowedTypes value. >2012-12-11 12:42:58,614 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) VDSBrokerFrontend: 12/11/12 12:42 PM >2012-12-11 12:42:58,625 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) CpuFlagsManager: 12/11/12 12:42 PM >2012-12-11 12:42:58,638 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Setting audit clean up manager to run at: 35 35 3 * * ? >2012-12-11 12:42:58,727 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) AuditLogCleanupManager: 12/11/12 12:42 PM >2012-12-11 12:42:58,750 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-2) TagsDirector initialization >2012-12-11 12:42:58,825 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-2) Tag root added to tree >2012-12-11 12:42:58,853 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) TagsDirector: 12/11/12 12:42 PM >2012-12-11 12:42:58,877 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) IsoDomainListSyncronizer: 12/11/12 12:42 PM >2012-12-11 12:42:58,970 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) InitHandlers: 12/11/12 12:42 PM >2012-12-11 12:42:59,020 WARN [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-2) Code VDS_FENCING_OPERATION_FAILED appears more then once in string table. >2012-12-11 12:42:59,022 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) ErrorTranslator: 12/11/12 12:42 PM >2012-12-11 12:42:59,029 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) VdsErrorTranslator: 12/11/12 12:42 PM >2012-12-11 12:42:59,029 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Mark uncompleted jobs as UNKNOWN: 12/11/12 12:42 PM >2012-12-11 12:42:59,130 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) JobRepositoryCleanupManager: 12/11/12 12:42 PM >2012-12-11 12:42:59,143 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) AutoRecoveryManager: 12/11/12 12:42 PM >2012-12-11 12:42:59,152 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) ExecutionMessageDirector: 12/11/12 12:42 PM >2012-12-11 12:42:59,242 INFO [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-2) UsersDomainsCacheManager: 12/11/12 12:42 PM >2012-12-11 12:42:59,243 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) InitResourceManager: 12/11/12 12:42 PM >2012-12-11 12:42:59,263 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-1) ResourceManager::ResourceManager::Entered >2012-12-11 12:42:59,364 INFO [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-2) DbUserCacheManager: 12/11/12 12:42 PM >2012-12-11 12:42:59,544 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-1) DbUserCacheManager::refreshAllUserData() - entered >2012-12-11 12:42:59,683 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (MSC service thread 1-1) AsyncTaskManager: Initialization of AsyncTaskManager completed successfully. >2012-12-11 12:42:59,684 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) AsyncTaskManager: 12/11/12 12:42 PM >2012-12-11 12:42:59,697 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) VdsLoadBalancer: 12/11/12 12:42 PM >2012-12-11 12:42:59,711 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (pool-3-thread-1) MacPoolManager started: 12/11/12 12:42 PM >2012-12-11 12:42:59,772 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) Init VM Custom Properties utilities: 12/11/12 12:42 PM >2012-12-11 12:42:59,834 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (pool-3-thread-1) MacPoolManager finished: 12/11/12 12:42 PM >2012-12-11 12:43:29,898 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Start time: 12/11/12 12:43 PM >2012-12-11 12:43:30,041 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: CbcCheckOnVdsChange >2012-12-11 12:43:30,059 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: CAEngineKey >2012-12-11 12:43:30,060 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: ENGINEEARLib >2012-12-11 12:43:30,077 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: MinimalETLVersion >2012-12-11 12:43:30,324 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: ScriptsPath >2012-12-11 12:43:30,329 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: SQLServerI18NPrefix >2012-12-11 12:43:30,704 ERROR [org.ovirt.engine.core.engineencryptutils.EncryptionUtils] (MSC service thread 1-2) Failed to decrypt Data must start with zero >2012-12-11 12:43:30,704 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-2) Failed to decrypt value for property TruststorePass will be used encrypted value >2012-12-11 12:43:30,721 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-2) Could not parse option AutoRecoveryAllowedTypes value. >2012-12-11 12:43:30,883 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) VDSBrokerFrontend: 12/11/12 12:43 PM >2012-12-11 12:43:30,892 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) CpuFlagsManager: 12/11/12 12:43 PM >2012-12-11 12:43:30,893 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Setting audit clean up manager to run at: 35 35 3 * * ? >2012-12-11 12:43:30,929 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) AuditLogCleanupManager: 12/11/12 12:43 PM >2012-12-11 12:43:30,936 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-2) TagsDirector initialization >2012-12-11 12:43:30,972 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-2) Tag root added to tree >2012-12-11 12:43:30,989 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) TagsDirector: 12/11/12 12:43 PM >2012-12-11 12:43:31,000 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) IsoDomainListSyncronizer: 12/11/12 12:43 PM >2012-12-11 12:43:31,054 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) InitHandlers: 12/11/12 12:43 PM >2012-12-11 12:43:31,077 WARN [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-2) Code VDS_FENCING_OPERATION_FAILED appears more then once in string table. >2012-12-11 12:43:31,078 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) ErrorTranslator: 12/11/12 12:43 PM >2012-12-11 12:43:31,082 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) VdsErrorTranslator: 12/11/12 12:43 PM >2012-12-11 12:43:31,083 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Mark uncompleted jobs as UNKNOWN: 12/11/12 12:43 PM >2012-12-11 12:43:31,130 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) JobRepositoryCleanupManager: 12/11/12 12:43 PM >2012-12-11 12:43:31,135 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) AutoRecoveryManager: 12/11/12 12:43 PM >2012-12-11 12:43:31,140 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) ExecutionMessageDirector: 12/11/12 12:43 PM >2012-12-11 12:43:31,174 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) InitResourceManager: 12/11/12 12:43 PM >2012-12-11 12:43:31,176 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-1) ResourceManager::ResourceManager::Entered >2012-12-11 12:43:31,180 INFO [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-2) UsersDomainsCacheManager: 12/11/12 12:43 PM >2012-12-11 12:43:31,277 INFO [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-2) DbUserCacheManager: 12/11/12 12:43 PM >2012-12-11 12:43:31,345 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-1) DbUserCacheManager::refreshAllUserData() - entered >2012-12-11 12:43:31,411 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (MSC service thread 1-1) AsyncTaskManager: Initialization of AsyncTaskManager completed successfully. >2012-12-11 12:43:31,412 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) AsyncTaskManager: 12/11/12 12:43 PM >2012-12-11 12:43:31,421 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) VdsLoadBalancer: 12/11/12 12:43 PM >2012-12-11 12:43:31,427 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (pool-3-thread-1) MacPoolManager started: 12/11/12 12:43 PM >2012-12-11 12:43:31,475 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) Init VM Custom Properties utilities: 12/11/12 12:43 PM >2012-12-11 12:43:31,504 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (pool-3-thread-1) MacPoolManager finished: 12/11/12 12:43 PM >2012-12-11 12:43:54,343 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-3) Checking if user admin@internal is an admin, result true >2012-12-11 12:43:54,346 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-3) Running command: LoginUserCommand internal: false. >2012-12-11 12:43:54,438 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: UNASSIGNED not exist in string table >2012-12-11 12:43:54,446 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: USER_RUN_UNLOCK_ENTITY_SCRIPT not exist in string table >2012-12-11 12:43:54,452 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: VDS_NETWORK_MTU_DIFFER_FROM_LOGICAL_NETWORK not exist in string table >2012-12-11 12:43:54,452 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: STORAGE_ACTIVATE_ASYNC not exist in string table >2012-12-11 12:43:54,453 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: DWH_STOPPED not exist in string table >2012-12-11 12:43:54,454 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: DWH_STARTED not exist in string table >2012-12-11 12:43:54,457 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: DWH_ERROR not exist in string table >2012-12-11 12:43:54,472 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: USER_ATTACH_DISK_TO_VM not have severity. Assumed Normal >2012-12-11 12:43:54,473 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: USER_FAILED_ATTACH_DISK_TO_VM not have severity. Assumed Normal >2012-12-11 12:43:54,473 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: USER_DETACH_DISK_FROM_VM not have severity. Assumed Normal >2012-12-11 12:43:54,474 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: USER_FAILED_DETACH_DISK_FROM_VM not have severity. Assumed Normal >2012-12-11 12:43:54,474 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: USER_RUN_UNLOCK_ENTITY_SCRIPT not have severity. Assumed Normal >2012-12-11 12:43:54,474 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: VM_MIGRATION_START_SYSTEM_INITIATED not have severity. Assumed Normal >2012-12-11 12:43:54,479 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) AuditLogType: VDS_NETWORK_MTU_DIFFER_FROM_LOGICAL_NETWORK not have severity. Assumed Normal >2012-12-11 12:43:54,541 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-3) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:43:56,665 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-4) Checking if user admin@internal is an admin, result true >2012-12-11 12:43:56,666 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-4) Running command: LoginUserCommand internal: false. >2012-12-11 12:43:56,718 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-4) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:43:58,178 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-4) calling GetConfigurationValueQuery (SupportedClusterLevels) with null version, using default general for version >2012-12-11 12:43:58,198 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-4) calling GetConfigurationValueQuery (VdcVersion) with null version, using default general for version >2012-12-11 12:44:27,971 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-5) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:27,980 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-5) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:28,053 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-5) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:28,202 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:28,210 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:28,263 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:29,148 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-1) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:29,153 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-1) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:29,176 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-1) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:29,739 INFO [org.ovirt.engine.core.bll.storage.AddEmptyStoragePoolCommand] (ajp--127.0.0.1-8702-1) [2f0af128] Running command: AddEmptyStoragePoolCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-12-11 12:44:29,824 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:29,826 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:29,840 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-2) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:30,006 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-3) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:30,008 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-3) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:30,026 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-3) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:30,385 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-4) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:30,387 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-4) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:30,401 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-4) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:30,577 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-7) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:30,580 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-7) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:30,597 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-7) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:30,689 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-8) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:30,691 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-8) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:30,708 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-8) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:30,866 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-9) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:30,869 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-9) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:30,885 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-9) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:30,976 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-10) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:30,979 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-10) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:30,990 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-10) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:31,137 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-11) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:31,141 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-11) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:31,155 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-11) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:31,263 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-12) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:31,265 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-12) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:31,278 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-12) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:31,415 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-13) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:31,420 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-13) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:31,484 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-13) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:31,578 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-14) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:31,586 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-14) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:31,603 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-14) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:31,742 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-15) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:31,747 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-15) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:31,759 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-15) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:31,843 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-16) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:31,845 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-16) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:31,862 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-16) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:32,014 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-17) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:32,016 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-17) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:32,029 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-17) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:32,113 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-18) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:32,119 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-18) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:32,143 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-18) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:32,291 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-19) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:32,292 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-19) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:32,306 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-19) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:32,405 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-20) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:32,408 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-20) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:32,424 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-20) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:32,573 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-21) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:32,586 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-21) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:32,611 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-21) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:32,695 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-22) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:32,697 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-22) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:32,712 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-22) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:32,869 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-23) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:32,872 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-23) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:32,885 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-23) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:32,973 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-24) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:32,975 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-24) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:32,993 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-24) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:33,135 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-25) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:33,137 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-25) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:33,154 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-25) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:33,261 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-26) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:33,268 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-26) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:33,284 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-26) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:33,416 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:33,418 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:33,431 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-27) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:33,522 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-28) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:33,524 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-28) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:33,538 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-28) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:33,768 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-29) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:33,773 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-29) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:33,800 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-29) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:33,868 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-30) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:33,870 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-30) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:33,884 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-30) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:34,473 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-31) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:34,475 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-31) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:34,486 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-31) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:35,022 INFO [org.ovirt.engine.core.bll.AddVdsGroupCommand] (ajp--127.0.0.1-8702-31) [3492f1e7] Running command: AddVdsGroupCommand internal: false. Entities affected : ID: 7f66783a-d412-488d-807b-fc6224a599e1 Type: StoragePool >2012-12-11 12:44:35,123 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:35,125 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:35,144 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-32) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:35,688 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:35,690 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:35,710 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-33) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:36,244 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:36,246 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:36,260 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-34) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:36,982 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-35) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:36,985 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-35) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:37,005 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-35) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:37,113 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-36) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:37,121 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-36) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:37,138 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-36) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:38,165 INFO [org.ovirt.engine.core.bll.AddVdsCommand] (ajp--127.0.0.1-8702-36) [41b005f9] Running command: AddVdsCommand internal: false. Entities affected : ID: f54af815-3b66-4372-934a-d88c0232b54c Type: VdsGroups >2012-12-11 12:44:38,339 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp--127.0.0.1-8702-36) [659bf1df] Running command: AddVdsSpmIdCommand internal: true. Entities affected : ID: 27a89504-8993-4e70-b1f5-e169905cc984 Type: VDS >2012-12-11 12:44:38,360 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp--127.0.0.1-8702-36) [659bf1df] Lock freed to object EngineLock [exclusiveLocks= key: 7f66783a-d412-488d-807b-fc6224a599e1 value: REGISTER_VDS >, sharedLocks= ] >2012-12-11 12:44:38,370 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp--127.0.0.1-8702-36) [659bf1df] Cannot get vdsManager for vdsid=27a89504-8993-4e70-b1f5-e169905cc984 >2012-12-11 12:44:38,394 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp--127.0.0.1-8702-36) [659bf1df] START, RemoveVdsVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = 27a89504-8993-4e70-b1f5-e169905cc984), log id: 6b7fc76 >2012-12-11 12:44:38,406 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp--127.0.0.1-8702-36) [659bf1df] Cannot get vdsManager for vdsid=27a89504-8993-4e70-b1f5-e169905cc984 >2012-12-11 12:44:38,411 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp--127.0.0.1-8702-36) [659bf1df] FINISH, RemoveVdsVDSCommand, log id: 6b7fc76 >2012-12-11 12:44:38,414 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp--127.0.0.1-8702-36) [659bf1df] Cannot get vdsManager for vdsid=27a89504-8993-4e70-b1f5-e169905cc984 >2012-12-11 12:44:38,428 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-36) [659bf1df] START, AddVdsVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = 27a89504-8993-4e70-b1f5-e169905cc984), log id: 2d86f779 >2012-12-11 12:44:38,430 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-36) [659bf1df] AddVds - entered , starting logic to add VDS 27a89504-8993-4e70-b1f5-e169905cc984 >2012-12-11 12:44:38,444 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-36) [659bf1df] AddVds - VDS 27a89504-8993-4e70-b1f5-e169905cc984 was added, will try to add it to the resource manager >2012-12-11 12:44:38,459 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp--127.0.0.1-8702-36) [659bf1df] Eneterd VdsManager:constructor >2012-12-11 12:44:38,462 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp--127.0.0.1-8702-36) [659bf1df] vdsBroker(10.35.148.46,54,321) >2012-12-11 12:44:38,596 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp--127.0.0.1-8702-36) [659bf1df] ResourceManager::AddVds - VDS 27a89504-8993-4e70-b1f5-e169905cc984 was added to the Resource Manager >2012-12-11 12:44:38,607 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-36) [659bf1df] FINISH, AddVdsVDSCommand, log id: 2d86f779 >2012-12-11 12:44:38,817 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-3-thread-40) [334f7ce2] Running command: InstallVdsCommand internal: true. Entities affected : ID: 27a89504-8993-4e70-b1f5-e169905cc984 Type: VDS >2012-12-11 12:44:38,820 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-3-thread-40) [334f7ce2] Before Installation host 27a89504-8993-4e70-b1f5-e169905cc984, cinteg04.ci.lab.tlv.redhat.com >2012-12-11 12:44:38,905 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:38,919 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:38,940 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:38,969 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Loaded file "/usr/share/ovirt-engine/conf/engine.conf.defaults". >2012-12-11 12:44:38,980 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Loaded file "/etc/sysconfig/ovirt-engine". >2012-12-11 12:44:38,989 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_DB_URL" is "jdbc:postgresql://localhost:5432/engine". >2012-12-11 12:44:38,993 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_PROPERTIES" is "". >2012-12-11 12:44:38,998 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_PKI" is "/etc/pki/ovirt-engine". >2012-12-11 12:44:39,002 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_PERM_MIN" is "256m". >2012-12-11 12:44:39,027 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "JBOSS_HOME" is "/usr/share/jboss-as". >2012-12-11 12:44:39,029 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_DB_USER" is "engine". >2012-12-11 12:44:39,051 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_LOG" is "/var/log/ovirt-engine". >2012-12-11 12:44:39,067 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_PROXY_HTTP_PORT" is "80". >2012-12-11 12:44:39,077 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_STOP_TIME" is "10". >2012-12-11 12:44:39,092 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_FQDN" is "vm-18-155.eng.lab.tlv.redhat.com". >2012-12-11 12:44:39,099 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_HEAP_MAX" is "1g". >2012-12-11 12:44:39,108 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_DB_MIN_CONNECTIONS" is "1". >2012-12-11 12:44:39,116 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_LOCK" is "/var/lock/ovirt-engine". >2012-12-11 12:44:39,123 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_STOP_INTERVAL" is "1". >2012-12-11 12:44:39,129 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_PROXY_ENABLED" is "true". >2012-12-11 12:44:39,135 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_DB_DRIVER" is "org.postgresql.Driver". >2012-12-11 12:44:39,145 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_APPS" is "engine.ear". >2012-12-11 12:44:39,146 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_DB_PASSWORD" is "64c5fd2979a86168". >2012-12-11 12:44:39,162 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_ETC" is "/etc/ovirt-engine". >2012-12-11 12:44:39,163 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_VAR" is "/var/lib/ovirt-engine". >2012-12-11 12:44:39,172 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_DB_MAX_CONNECTIONS" is "100". >2012-12-11 12:44:39,180 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_HTTP_PORT" is "8700". >2012-12-11 12:44:39,186 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_AJP_PORT" is "8702". >2012-12-11 12:44:39,217 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_PERM_MAX" is "256m". >2012-12-11 12:44:39,231 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_VERBOSE_GC" is "false". >2012-12-11 12:44:39,232 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_HTTPS_PORT" is "8701". >2012-12-11 12:44:39,234 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_DEBUG_ADDRESS" is "". >2012-12-11 12:44:39,242 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_CACHE" is "/var/cache/ovirt-engine". >2012-12-11 12:44:39,250 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_NOFILE" is "65535". >2012-12-11 12:44:39,259 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_PROXY_HTTPS_PORT" is "443". >2012-12-11 12:44:39,260 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_PID" is "/var/run/ovirt-engine.pid". >2012-12-11 12:44:39,269 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_USR" is "/usr/share/ovirt-engine". >2012-12-11 12:44:39,278 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_HTTP_ENABLED" is "false". >2012-12-11 12:44:39,279 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_GROUP" is "ovirt". >2012-12-11 12:44:39,282 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "JAVA_HOME" is "/usr/lib/jvm/jre-1.7.0-openjdk.x86_64". >2012-12-11 12:44:39,294 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_HTTPS_ENABLED" is "false". >2012-12-11 12:44:39,294 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_DB_SSL" is "false". >2012-12-11 12:44:39,295 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_TMP" is "/var/tmp/ovirt-engine". >2012-12-11 12:44:39,296 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_HEAP_MIN" is "1g". >2012-12-11 12:44:39,296 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_AJP_ENABLED" is "true". >2012-12-11 12:44:39,296 INFO [org.ovirt.engine.core.utils.LocalConfig] (pool-3-thread-40) Value of property "ENGINE_USER" is "ovirt". >2012-12-11 12:44:39,547 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:39,553 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:39,565 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-2) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:39,598 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-3-thread-40) [334f7ce2] Installation 10.35.148.46: Connected to host 10.35.148.46 with SSH key fingerprint: f0:fd:e4:79:b6:20:c3:63:71:22:c2:29:e0:38:66:ee >2012-12-11 12:44:39,672 INFO [org.ovirt.engine.core.bll.VdsDeploy] (pool-3-thread-40) [334f7ce2] Installation of 10.35.148.46. Executing command via SSH umask 0077; MYTMP="$(mktemp -t ovirt-XXXXXXXXXX)"; trap "chmod -R u+rwX \"${MYTMP}\" > /dev/null 2>&1; rm -fr \"${MYTMP}\" > /dev/null 2>&1" 0; rm -fr "${MYTMP}" && mkdir "${MYTMP}" && python -c "import sys, tarfile; tarfile.open(fileobj=sys.stdin, mode='r|').extractall(path='${MYTMP}')" && "${MYTMP}"/setup DIALOG/dialect=str:machine DIALOG/customization=bool:True < /var/cache/ovirt-engine/ovirt-host-deploy.tar >2012-12-11 12:44:39,713 INFO [org.ovirt.engine.core.utils.ssh.SSHDialog] (pool-3-thread-40) SSH execute root@10.35.148.46 'umask 0077; MYTMP="$(mktemp -t ovirt-XXXXXXXXXX)"; trap "chmod -R u+rwX \"${MYTMP}\" > /dev/null 2>&1; rm -fr \"${MYTMP}\" > /dev/null 2>&1" 0; rm -fr "${MYTMP}" && mkdir "${MYTMP}" && python -c "import sys, tarfile; tarfile.open(fileobj=sys.stdin, mode='r|').extractall(path='${MYTMP}')" && "${MYTMP}"/setup DIALOG/dialect=str:machine DIALOG/customization=bool:True' >2012-12-11 12:44:39,726 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-3) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:39,748 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-3) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:39,792 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-3) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:44:40,439 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Initializing >2012-12-11 12:44:40,460 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Environment setup >2012-12-11 12:44:40,551 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Installation packages setup >2012-12-11 12:44:40,598 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-30) Initializing Host: cinteg04.ci.lab.tlv.redhat.com >2012-12-11 12:44:44,469 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Programs detection >2012-12-11 12:44:44,492 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Environment customization >2012-12-11 12:44:44,511 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Logs at host located at: '/tmp/ovirt-host-deploy-20121211124440.log' >2012-12-11 12:44:44,578 INFO [org.ovirt.engine.core.bll.VdsDeploy] (VdsDeploy) Host 10.35.148.46 reports unique id e3dcd4be-1162-4eec-9d36-c120696b3902 >2012-12-11 12:44:44,622 INFO [org.ovirt.engine.core.bll.VdsDeploy] (VdsDeploy) Assigning unique id e3dcd4be-1162-4eec-9d36-c120696b3902 to Host 10.35.148.46 >2012-12-11 12:44:44,685 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Enfocing host reboot >2012-12-11 12:44:44,700 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Setup validation >2012-12-11 12:44:44,719 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Hardware supports virtualization >2012-12-11 12:44:44,732 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Transaction setup >2012-12-11 12:44:44,740 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Package installation >2012-12-11 12:44:46,035 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Misc configuration >2012-12-11 12:44:46,582 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Enrolling certificate >2012-12-11 12:44:47,721 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Transaction commit >2012-12-11 12:44:47,739 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Closing up >2012-12-11 12:44:47,802 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Reboot scheduled >2012-12-11 12:44:47,816 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Pre-termination >2012-12-11 12:44:47,845 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Reboot scheduled >2012-12-11 12:44:47,859 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Retrieving installation logs to: '/var/log/ovirt-engine/host-deploy/ovirt-20121211124447-10.35.148.46.log' >2012-12-11 12:44:48,009 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.148.46: Stage: Termination >2012-12-11 12:44:48,078 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-3-thread-40) [334f7ce2] After Installation host cinteg04.ci.lab.tlv.redhat.com, VDS >2012-12-11 12:44:48,087 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-3-thread-44) [334f7ce2] Waiting 150 seconds, for server to finish reboot process. >2012-12-11 12:44:49,907 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-16) Checking if user admin@internal is an admin, result true >2012-12-11 12:44:49,919 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-16) Running command: LoginUserCommand internal: false. >2012-12-11 12:44:49,939 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-16) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:45:00,007 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-48) Autorecovering hosts is disabled, skipping >2012-12-11 12:45:00,013 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-48) Autorecovering storage domains is disabled, skipping >2012-12-11 12:45:00,102 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Checking if user admin@internal is an admin, result true >2012-12-11 12:45:00,105 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Running command: LoginUserCommand internal: false. >2012-12-11 12:45:00,125 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-27) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:45:10,221 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Checking if user admin@internal is an admin, result true >2012-12-11 12:45:10,224 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Running command: LoginUserCommand internal: false. >2012-12-11 12:45:10,238 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-32) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:45:20,450 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Checking if user admin@internal is an admin, result true >2012-12-11 12:45:20,454 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Running command: LoginUserCommand internal: false. >2012-12-11 12:45:20,479 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-33) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:45:30,571 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Checking if user admin@internal is an admin, result true >2012-12-11 12:45:30,574 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Running command: LoginUserCommand internal: false. >2012-12-11 12:45:30,587 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-34) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:45:40,782 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-35) Checking if user admin@internal is an admin, result true >2012-12-11 12:45:40,785 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-35) Running command: LoginUserCommand internal: false. >2012-12-11 12:45:40,800 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-35) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:45:50,880 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-36) Checking if user admin@internal is an admin, result true >2012-12-11 12:45:50,887 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-36) Running command: LoginUserCommand internal: false. >2012-12-11 12:45:50,908 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-36) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:46:01,118 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Checking if user admin@internal is an admin, result true >2012-12-11 12:46:01,121 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Running command: LoginUserCommand internal: false. >2012-12-11 12:46:01,143 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:46:11,234 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Checking if user admin@internal is an admin, result true >2012-12-11 12:46:11,237 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Running command: LoginUserCommand internal: false. >2012-12-11 12:46:11,260 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-2) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:46:21,446 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-3) Checking if user admin@internal is an admin, result true >2012-12-11 12:46:21,454 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-3) Running command: LoginUserCommand internal: false. >2012-12-11 12:46:21,474 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-3) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:46:31,592 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-16) Checking if user admin@internal is an admin, result true >2012-12-11 12:46:31,595 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-16) Running command: LoginUserCommand internal: false. >2012-12-11 12:46:31,612 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-16) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:46:41,797 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Checking if user admin@internal is an admin, result true >2012-12-11 12:46:41,800 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Running command: LoginUserCommand internal: false. >2012-12-11 12:46:41,815 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-27) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:46:51,923 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Checking if user admin@internal is an admin, result true >2012-12-11 12:46:51,930 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Running command: LoginUserCommand internal: false. >2012-12-11 12:46:51,954 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-32) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:02,122 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:02,133 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:02,165 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-33) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:12,254 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:12,258 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:12,277 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-34) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:18,097 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-3-thread-44) [334f7ce2] START, SetVdsStatusVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = 27a89504-8993-4e70-b1f5-e169905cc984, status=NonResponsive, nonOperationalReason=NONE), log id: 155e403a >2012-12-11 12:47:18,118 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-3-thread-44) [334f7ce2] FINISH, SetVdsStatusVDSCommand, log id: 155e403a >2012-12-11 12:47:20,831 INFO [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (QuartzScheduler_Worker-81) [4df2d31f] Running command: InitVdsOnUpCommand internal: true. >2012-12-11 12:47:20,857 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (QuartzScheduler_Worker-81) [4df2d31f] No string for UNASSIGNED type. Use default Log >2012-12-11 12:47:20,875 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-3-thread-44) [4df2d31f] MultipleActionsRunner of type MigrateVmToServer invoked with no actions >2012-12-11 12:47:20,924 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (QuartzScheduler_Worker-81) [3002f352] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: 27a89504-8993-4e70-b1f5-e169905cc984 Type: VDS >2012-12-11 12:47:20,952 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (QuartzScheduler_Worker-81) [40783dc0] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: 27a89504-8993-4e70-b1f5-e169905cc984 Type: VDS >2012-12-11 12:47:22,475 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-35) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:22,478 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-35) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:22,524 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-35) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:22,851 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-36) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:22,853 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-36) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:22,875 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-36) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:23,029 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:23,031 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:23,048 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:23,134 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:23,140 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:23,154 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-2) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:23,289 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-3) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:23,291 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-3) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:23,306 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-3) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:23,380 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-16) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:23,384 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-16) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:23,411 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-16) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:23,533 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:23,535 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:23,552 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-27) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:23,633 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:23,635 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-32) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:23,650 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-32) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:23,769 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:23,771 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-33) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:23,782 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-33) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:23,861 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:23,863 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-34) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:23,876 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-34) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:23,995 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-31) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:23,996 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-31) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:24,015 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-31) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:24,426 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-30) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:24,431 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-30) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:24,448 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-30) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:24,590 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-29) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:24,592 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-29) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:24,603 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-29) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:25,457 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-28) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:25,463 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-28) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:25,493 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-28) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:25,698 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-26) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:25,700 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-26) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:25,714 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-26) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:26,310 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DiscoverSendTargetsVDSCommand] (ajp--127.0.0.1-8702-26) START, DiscoverSendTargetsVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = 27a89504-8993-4e70-b1f5-e169905cc984, connection={ id: null, connection: 10.35.148.12, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };), log id: 20a23c86 >2012-12-11 12:47:26,456 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DiscoverSendTargetsVDSCommand] (ajp--127.0.0.1-8702-26) FINISH, DiscoverSendTargetsVDSCommand, return: [{ id: null, connection: 10.35.148.12, iqn: iqn.1992-08.com.netapp:sn.1574937817:vf.af72f996-a4d9-11e1-a00b-00a0981c197e, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };], log id: 20a23c86 >2012-12-11 12:47:26,834 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-25) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:26,837 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-25) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:26,855 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-25) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:27,104 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-24) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:27,112 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-24) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:27,137 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-24) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:27,161 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DiscoverSendTargetsVDSCommand] (ajp--127.0.0.1-8702-24) START, DiscoverSendTargetsVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = 27a89504-8993-4e70-b1f5-e169905cc984, connection={ id: null, connection: 10.35.148.12, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };), log id: 62724d13 >2012-12-11 12:47:29,804 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DiscoverSendTargetsVDSCommand] (ajp--127.0.0.1-8702-24) FINISH, DiscoverSendTargetsVDSCommand, return: [{ id: null, connection: 10.35.148.12, iqn: iqn.1992-08.com.netapp:sn.1574937817:vf.af72f996-a4d9-11e1-a00b-00a0981c197e, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };], log id: 62724d13 >2012-12-11 12:47:30,124 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-23) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:30,126 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-23) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:30,143 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-23) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:30,266 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-22) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:30,268 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-22) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:30,282 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-22) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:30,358 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--127.0.0.1-8702-22) [7fcfaf57] START, ValidateStorageServerConnectionVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = 27a89504-8993-4e70-b1f5-e169905cc984, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = ISCSI, connectionList = [{ id: null, connection: 10.35.148.12, iqn: iqn.1992-08.com.netapp:sn.1574937817:vf.af72f996-a4d9-11e1-a00b-00a0981c197e, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 21e835e >2012-12-11 12:47:30,429 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--127.0.0.1-8702-22) [7fcfaf57] FINISH, ValidateStorageServerConnectionVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 21e835e >2012-12-11 12:47:30,442 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (ajp--127.0.0.1-8702-22) [7fcfaf57] Running command: ConnectStorageToVdsCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-12-11 12:47:30,447 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-22) [7fcfaf57] START, ConnectStorageServerVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = 27a89504-8993-4e70-b1f5-e169905cc984, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = ISCSI, connectionList = [{ id: null, connection: 10.35.148.12, iqn: iqn.1992-08.com.netapp:sn.1574937817:vf.af72f996-a4d9-11e1-a00b-00a0981c197e, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 7d10a28c >2012-12-11 12:47:30,524 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-22) [7fcfaf57] FINISH, ConnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 7d10a28c >2012-12-11 12:47:30,789 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-21) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:30,791 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-21) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:30,808 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-21) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:30,919 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-20) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:30,921 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-20) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:30,934 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-20) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:30,974 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--127.0.0.1-8702-20) [3f46d0c0] START, ValidateStorageServerConnectionVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = 27a89504-8993-4e70-b1f5-e169905cc984, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = ISCSI, connectionList = [{ id: null, connection: 10.35.148.12, iqn: iqn.1992-08.com.netapp:sn.1574937817:vf.af72f996-a4d9-11e1-a00b-00a0981c197e, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 423ad861 >2012-12-11 12:47:31,023 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--127.0.0.1-8702-20) [3f46d0c0] FINISH, ValidateStorageServerConnectionVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 423ad861 >2012-12-11 12:47:31,037 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (ajp--127.0.0.1-8702-20) [3f46d0c0] Running command: ConnectStorageToVdsCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-12-11 12:47:31,051 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-20) [3f46d0c0] START, ConnectStorageServerVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = 27a89504-8993-4e70-b1f5-e169905cc984, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = ISCSI, connectionList = [{ id: null, connection: 10.35.148.12, iqn: iqn.1992-08.com.netapp:sn.1574937817:vf.af72f996-a4d9-11e1-a00b-00a0981c197e, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 5ea5e10d >2012-12-11 12:47:31,148 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-20) [3f46d0c0] FINISH, ConnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 5ea5e10d >2012-12-11 12:47:31,469 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-19) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:31,475 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-19) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:31,487 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-19) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:31,661 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-18) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:31,664 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-18) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:31,682 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-18) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:31,780 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-35) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:31,783 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-35) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:31,806 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-35) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:31,936 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-36) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:31,939 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-36) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:31,952 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-36) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:31,999 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:32,002 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-6) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:32,016 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:32,540 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:32,542 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-2) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:32,555 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-2) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version >2012-12-11 12:47:33,063 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--127.0.0.1-8702-2) [7290e000] START, ValidateStorageServerConnectionVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = 27a89504-8993-4e70-b1f5-e169905cc984, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = ISCSI, connectionList = [{ id: null, connection: 10.35.148.12, iqn: iqn.1992-08.com.netapp:sn.1574937817:vf.af72f996-a4d9-11e1-a00b-00a0981c197e, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 4bfcb0b >2012-12-11 12:47:33,138 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--127.0.0.1-8702-2) [7290e000] FINISH, ValidateStorageServerConnectionVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 4bfcb0b >2012-12-11 12:47:33,153 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (ajp--127.0.0.1-8702-2) [7290e000] Running command: ConnectStorageToVdsCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-12-11 12:47:33,157 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-2) [7290e000] START, ConnectStorageServerVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = 27a89504-8993-4e70-b1f5-e169905cc984, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = ISCSI, connectionList = [{ id: null, connection: 10.35.148.12, iqn: iqn.1992-08.com.netapp:sn.1574937817:vf.af72f996-a4d9-11e1-a00b-00a0981c197e, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 3efa6899 >2012-12-11 12:47:33,244 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-2) [7290e000] FINISH, ConnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 3efa6899 >2012-12-11 12:47:33,264 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetDeviceListVDSCommand] (ajp--127.0.0.1-8702-2) START, GetDeviceListVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = 27a89504-8993-4e70-b1f5-e169905cc984, storageType=ISCSI), log id: 1f14adfa >2012-12-11 12:47:35,292 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetDeviceListVDSCommand] (ajp--127.0.0.1-8702-2) FINISH, GetDeviceListVDSCommand, return: [org.ovirt.engine.core.common.businessentities.LUNs@b62f7b62, org.ovirt.engine.core.common.businessentities.LUNs@e0976e1, org.ovirt.engine.core.common.businessentities.LUNs@415c3ebf], log id: 1f14adfa >2012-12-11 12:47:35,471 INFO [org.ovirt.engine.core.bll.storage.AddSANStorageDomainCommand] (ajp--127.0.0.1-8702-2) [34da856c] Running command: AddSANStorageDomainCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-12-11 12:47:35,502 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVGVDSCommand] (ajp--127.0.0.1-8702-2) [34da856c] START, CreateVGVDSCommand(HostName = cinteg04.ci.lab.tlv.redhat.com, HostId = 27a89504-8993-4e70-b1f5-e169905cc984, storageDomainId=ba9a9438-f100-4ce5-924f-26f01a1e6fb8, deviceList=[bash: /sbin/scsi_id: No such file or directory], force=false), log id: 8566358 >2012-12-11 12:47:35,554 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp--127.0.0.1-8702-2) [34da856c] Failed in CreateVGVDS method >2012-12-11 12:47:35,556 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp--127.0.0.1-8702-2) [34da856c] Error code unexpected and error message VDSGenericException: VDSErrorException: Failed to CreateVGVDS, error = Unexpected exception >2012-12-11 12:47:35,563 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp--127.0.0.1-8702-2) [34da856c] Command org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVGVDSCommand return value > Class Name: org.ovirt.engine.core.vdsbroker.irsbroker.OneUuidReturnForXmlRpc >mUuid Null >mStatus Class Name: org.ovirt.engine.core.vdsbroker.vdsbroker.StatusForXmlRpc >mCode 16 >mMessage Unexpected exception > > >2012-12-11 12:47:35,568 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp--127.0.0.1-8702-2) [34da856c] HostName = cinteg04.ci.lab.tlv.redhat.com >2012-12-11 12:47:35,573 ERROR [org.ovirt.engine.core.vdsbroker.VDSCommandBase] (ajp--127.0.0.1-8702-2) [34da856c] Command CreateVGVDS execution failed. Exception: VDSErrorException: VDSGenericException: VDSErrorException: Failed to CreateVGVDS, error = Unexpected exception >2012-12-11 12:47:35,575 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVGVDSCommand] (ajp--127.0.0.1-8702-2) [34da856c] FINISH, CreateVGVDSCommand, log id: 8566358 >2012-12-11 12:47:35,577 ERROR [org.ovirt.engine.core.bll.storage.AddSANStorageDomainCommand] (ajp--127.0.0.1-8702-2) [34da856c] Command org.ovirt.engine.core.bll.storage.AddSANStorageDomainCommand throw Vdc Bll exception. With error message VdcBLLException: org.ovirt.engine.core.vdsbroker.vdsbroker.VDSErrorException: VDSGenericException: VDSErrorException: Failed to CreateVGVDS, error = Unexpected exception >2012-12-11 12:47:35,609 INFO [org.ovirt.engine.core.bll.storage.AddSANStorageDomainCommand] (ajp--127.0.0.1-8702-2) [34da856c] Command [id=5ab4d7c0-16ad-4618-869f-b7c58cc18a6d]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.storage_domain_dynamic; snapshot: ba9a9438-f100-4ce5-924f-26f01a1e6fb8. >2012-12-11 12:47:35,622 INFO [org.ovirt.engine.core.bll.storage.AddSANStorageDomainCommand] (ajp--127.0.0.1-8702-2) [34da856c] Command [id=5ab4d7c0-16ad-4618-869f-b7c58cc18a6d]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.storage_domain_static; snapshot: ba9a9438-f100-4ce5-924f-26f01a1e6fb8. >2012-12-11 12:47:35,642 ERROR [org.ovirt.engine.core.bll.storage.AddSANStorageDomainCommand] (ajp--127.0.0.1-8702-2) [34da856c] Transaction rolled-back for command: org.ovirt.engine.core.bll.storage.AddSANStorageDomainCommand. >2012-12-11 12:47:35,669 ERROR [org.ovirt.engine.api.restapi.resource.AbstractBackendResource] (ajp--127.0.0.1-8702-2) Operation Failed: [Unexpected exception] >2012-12-11 12:47:36,166 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Checking if user admin@internal is an admin, result true >2012-12-11 12:47:36,168 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-27) Running command: LoginUserCommand internal: false. >2012-12-11 12:47:36,182 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp--127.0.0.1-8702-27) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 886087
:
669598
| 669599