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 913728 Details for
Bug 1114987
Cannot start VM | The host did not satisfy internal filter Network because network(s) are missing.
[?]
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), 222.87 KB, created by
Meni Yakove
on 2014-07-01 11:40:25 UTC
(
hide
)
Description:
engine log
Filename:
MIME Type:
Creator:
Meni Yakove
Created:
2014-07-01 11:40:25 UTC
Size:
222.87 KB
patch
obsolete
>2014-07-01 04:09:49,020 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Loaded file "/usr/share/ovirt-engine/services/ovirt-engine/ovirt-engine.conf". >2014-07-01 04:09:49,030 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) The file "/etc/ovirt-engine/engine.conf" doesn't exist or isn't readable. Will return an empty set of properties. >2014-07-01 04:09:49,031 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Loaded file "/etc/ovirt-engine/engine.conf.d/10-setup-database.conf". >2014-07-01 04:09:49,031 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Loaded file "/etc/ovirt-engine/engine.conf.d/10-setup-jboss.conf". >2014-07-01 04:09:49,035 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Loaded file "/etc/ovirt-engine/engine.conf.d/10-setup-pki.conf". >2014-07-01 04:09:49,035 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Loaded file "/etc/ovirt-engine/engine.conf.d/10-setup-protocols.conf". >2014-07-01 04:09:49,036 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_AJP_ENABLED" is "true". >2014-07-01 04:09:49,036 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_AJP_PORT" is "8702". >2014-07-01 04:09:49,037 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_APPS" is "engine.ear". >2014-07-01 04:09:49,041 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_CACHE" is "/var/cache/ovirt-engine". >2014-07-01 04:09:49,041 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_CHECK_INTERVAL" is "1000". >2014-07-01 04:09:49,042 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_CONNECTION_TIMEOUT" is "300000". >2014-07-01 04:09:49,042 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_DATABASE" is "engine". >2014-07-01 04:09:49,042 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_DRIVER" is "org.postgresql.Driver". >2014-07-01 04:09:49,052 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_HOST" is "localhost". >2014-07-01 04:09:49,053 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_MAX_CONNECTIONS" is "100". >2014-07-01 04:09:49,053 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_MIN_CONNECTIONS" is "1". >2014-07-01 04:09:49,053 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_PASSWORD" is "***". >2014-07-01 04:09:49,054 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_PORT" is "5432". >2014-07-01 04:09:49,056 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_SECURED" is "False". >2014-07-01 04:09:49,056 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_SECURED_VALIDATION" is "False". >2014-07-01 04:09:49,057 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_URL" is "jdbc:postgresql://localhost:5432/engine?sslfactory=org.postgresql.ssl.NonValidatingFactory". >2014-07-01 04:09:49,057 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_USER" is "engine". >2014-07-01 04:09:49,057 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DEBUG_ADDRESS" is "". >2014-07-01 04:09:49,058 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DOC" is "/usr/share/doc/ovirt-engine". >2014-07-01 04:09:49,064 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_ETC" is "/etc/ovirt-engine". >2014-07-01 04:09:49,064 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_EXTENSION_PATH" is "/usr/share/ovirt-engine/extensions.d:/etc/ovirt-engine/extensions.d". >2014-07-01 04:09:49,065 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_FQDN" is "vm-17-194.eng.lab.tlv.redhat.com". >2014-07-01 04:09:49,065 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_GROUP" is "ovirt". >2014-07-01 04:09:49,066 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_HEAP_MAX" is "1g". >2014-07-01 04:09:49,072 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_HEAP_MIN" is "1g". >2014-07-01 04:09:49,072 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_HTTPS_ENABLED" is "false". >2014-07-01 04:09:49,073 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_HTTPS_PORT" is "None". >2014-07-01 04:09:49,073 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_HTTPS_PROTOCOLS" is "SSLv3,TLSv1,TLSv1.1,TLSv1.2". >2014-07-01 04:09:49,073 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_HTTP_ENABLED" is "false". >2014-07-01 04:09:49,074 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_HTTP_PORT" is "None". >2014-07-01 04:09:49,080 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_JAVA_MODULEPATH" is "/usr/share/ovirt-engine/modules". >2014-07-01 04:09:49,080 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_JVM_ARGS" is " -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath="/var/log/ovirt-engine/dump"". >2014-07-01 04:09:49,081 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_LOG" is "/var/log/ovirt-engine". >2014-07-01 04:09:49,081 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_LOG_TO_CONSOLE" is "false". >2014-07-01 04:09:49,082 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_MANUAL" is "/usr/share/ovirt-engine/manual". >2014-07-01 04:09:49,087 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PERM_MAX" is "256m". >2014-07-01 04:09:49,087 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PERM_MIN" is "256m". >2014-07-01 04:09:49,087 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PKI" is "/etc/pki/ovirt-engine". >2014-07-01 04:09:49,088 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PKI_CA" is "/etc/pki/ovirt-engine/ca.pem". >2014-07-01 04:09:49,096 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PKI_ENGINE_CERT" is "/etc/pki/ovirt-engine/certs/engine.cer". >2014-07-01 04:09:49,096 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PKI_ENGINE_STORE" is "/etc/pki/ovirt-engine/keys/engine.p12". >2014-07-01 04:09:49,097 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PKI_ENGINE_STORE_ALIAS" is "1". >2014-07-01 04:09:49,097 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PKI_ENGINE_STORE_PASSWORD" is "***". >2014-07-01 04:09:49,098 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PKI_TRUST_STORE" is "/etc/pki/ovirt-engine/.truststore". >2014-07-01 04:09:49,103 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PKI_TRUST_STORE_PASSWORD" is "***". >2014-07-01 04:09:49,103 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PROPERTIES" is " jsse.enableSNIExtension=false "java.security.krb5.conf=/etc/ovirt-engine/krb5.conf"". >2014-07-01 04:09:49,107 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PROXY_ENABLED" is "true". >2014-07-01 04:09:49,108 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PROXY_HTTPS_PORT" is "443". >2014-07-01 04:09:49,108 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PROXY_HTTP_PORT" is "80". >2014-07-01 04:09:49,108 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_REPORTS_UI" is "/var/lib/ovirt-engine/reports.xml". >2014-07-01 04:09:49,109 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_STOP_INTERVAL" is "1". >2014-07-01 04:09:49,110 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_STOP_TIME" is "10". >2014-07-01 04:09:49,111 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_TMP" is "/var/tmp/ovirt-engine". >2014-07-01 04:09:49,111 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_UP_MARK" is "/var/lib/ovirt-engine/engine.up". >2014-07-01 04:09:49,111 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_URI" is "/ovirt-engine". >2014-07-01 04:09:49,112 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_USER" is "ovirt". >2014-07-01 04:09:49,121 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_USR" is "/usr/share/ovirt-engine". >2014-07-01 04:09:49,121 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_VAR" is "/var/lib/ovirt-engine". >2014-07-01 04:09:49,122 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_VERBOSE_GC" is "false". >2014-07-01 04:09:49,122 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "JBOSS_HOME" is "/usr/share/ovirt-engine-jboss-as". >2014-07-01 04:09:49,122 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "SENSITIVE_KEYS" is ",ENGINE_DB_PASSWORD,ENGINE_PKI_TRUST_STORE_PASSWORD,ENGINE_PKI_ENGINE_STORE_PASSWORD". >2014-07-01 04:09:50,101 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Start initializing Backend >2014-07-01 04:09:50,209 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: HotPlugCpuEnabled >2014-07-01 04:09:50,214 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: HotPlugCpuEnabled >2014-07-01 04:09:50,214 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: HotPlugCpuEnabled >2014-07-01 04:09:50,215 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: HotPlugCpuEnabled >2014-07-01 04:09:50,217 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: HotPlugCpuEnabled >2014-07-01 04:09:50,218 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: HotPlugCpuEnabled >2014-07-01 04:09:50,727 INFO [org.ovirt.engine.core.utils.osinfo.OsInfoPreferencesLoader] (MSC service thread 1-2) Loaded file /etc/ovirt-engine/osinfo.conf.d/00-defaults.properties >2014-07-01 04:09:50,886 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Running ovirt-engine 3.5.0-0.0.master.20140629172257.git0b16ed7.el6 >2014-07-01 04:09:50,887 INFO [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-2) Start initializing dictionaries >2014-07-01 04:09:50,896 INFO [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-2) Finished initializing dictionaries >2014-07-01 04:09:50,896 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Start initializing AuditLogCleanupManager >2014-07-01 04:09:50,897 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Setting audit cleanup manager to run at: 35 35 3 * * ? >2014-07-01 04:09:50,936 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Finished initializing AuditLogCleanupManager >2014-07-01 04:09:50,936 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Start initializing CommandEntityCleanupManager >2014-07-01 04:09:50,937 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Setting command entity cleanup manager to run at: 35 35 3 * * ? >2014-07-01 04:09:50,943 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Finished initializing CommandEntityCleanupManager >2014-07-01 04:09:50,948 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-2) Start initializing TagsDirector >2014-07-01 04:09:50,968 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-2) Tag root added to tree >2014-07-01 04:09:50,986 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-2) Finished initializing TagsDirector >2014-07-01 04:09:50,987 INFO [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-2) Start initializing IsoDomainListSyncronizer >2014-07-01 04:09:51,001 INFO [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-2) Finished initializing IsoDomainListSyncronizer >2014-07-01 04:09:51,147 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Completed initializing handlers >2014-07-01 04:09:51,193 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-2) Start initializing ErrorTranslatorImpl >2014-07-01 04:09:51,216 WARN [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-2) Code MAC_ADDRESS_IS_IN_USE appears more than once in string table. >2014-07-01 04:09:51,217 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-2) Finished initializing ErrorTranslatorImpl >2014-07-01 04:09:51,217 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-2) Start initializing ErrorTranslatorImpl >2014-07-01 04:09:51,218 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-2) Finished initializing ErrorTranslatorImpl >2014-07-01 04:09:51,219 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Mark incomplete jobs as UNKNOWN >2014-07-01 04:09:51,271 INFO [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-2) Start initializing JobRepositoryCleanupManager >2014-07-01 04:09:51,272 INFO [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-2) Finished initializing JobRepositoryCleanupManager >2014-07-01 04:09:51,273 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-2) Start initializing AutoRecoveryManager >2014-07-01 04:09:51,281 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-2) Finished initializing AutoRecoveryManager >2014-07-01 04:09:51,282 INFO [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-2) Start initializing ExecutionMessageDirector >2014-07-01 04:09:51,288 INFO [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-2) Finished initializing ExecutionMessageDirector >2014-07-01 04:09:51,358 INFO [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Start of enabled extensions list >2014-07-01 04:09:51,358 INFO [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Instance name: 'builtin-authn-internal', Extension name: 'Internal Authn (Built-in)', Version: 'N/A', Notes: '', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0', File: 'N/A', Initialized: 'true' >2014-07-01 04:09:51,359 INFO [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Instance name: 'internal', Extension name: 'Internal Authz (Built-in)', Version: 'N/A', Notes: '', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0', File: 'N/A', Initialized: 'true' >2014-07-01 04:09:51,361 INFO [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) End of enabled extensions list >2014-07-01 04:09:51,433 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (MSC service thread 1-1) Start initializing DbUserCacheManager >2014-07-01 04:09:51,437 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (MSC service thread 1-1) Finished initializing DbUserCacheManager >2014-07-01 04:09:51,464 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (MSC service thread 1-1) Initialization of AsyncTaskManager completed successfully. >2014-07-01 04:09:51,469 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-1) Start initializing ResourceManager >2014-07-01 04:09:51,605 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-1) Finished initializing ResourceManager >2014-07-01 04:09:51,614 INFO [org.ovirt.engine.core.bll.OvfDataUpdater] (MSC service thread 1-1) Initialization of OvfDataUpdater completed successfully. >2014-07-01 04:09:51,614 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-1) Start scheduling to enable vds load balancer >2014-07-01 04:09:51,618 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-1) Finished scheduling to enable vds load balancer >2014-07-01 04:09:51,618 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-1) Start HA Reservation check >2014-07-01 04:09:51,619 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-1) Finished HA Reservation check >2014-07-01 04:09:51,629 INFO [org.ovirt.engine.core.bll.network.MacPoolManagerRanges] (org.ovirt.thread.pool-8-thread-1) Start initializing MacPoolManagerRanges >2014-07-01 04:09:51,636 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) Init VM custom properties utilities >2014-07-01 04:09:51,637 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) Init device custom properties utilities >2014-07-01 04:09:51,651 INFO [org.ovirt.engine.core.bll.network.MacPoolManagerRanges] (org.ovirt.thread.pool-8-thread-1) Finished initializing. Available MACs in pool: 255 >2014-07-01 04:09:51,653 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-1) Initializing Scheduling manager >2014-07-01 04:09:51,713 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-1) External scheduler disabled, discovery skipped >2014-07-01 04:09:51,713 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-1) Initialized Scheduling manager >2014-07-01 04:09:51,713 INFO [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-1) Initializing DWH Heart Beat >2014-07-01 04:09:51,728 INFO [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-1) DWH Heart Beat initialized >2014-07-01 04:10:20,218 INFO [org.ovirt.engine.core.bll.LoginAdminUserCommand] (ajp--127.0.0.1-8702-7) Running command: LoginAdminUserCommand internal: false. >2014-07-01 04:10:20,234 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) Cannot convert the string Host ${VdsName} is not responding. It will stay in Connecting state for a grace period of ${Seconds} seconds and after that an attempt to fence the host will be issued. to AuditLogType, the key does not exist in the AuditLogType declared types >2014-07-01 04:10:20,243 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: UNASSIGNED not exist in string table >2014-07-01 04:10:20,243 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: VDS_AUTO_FENCE_STATUS not exist in string table >2014-07-01 04:10:20,244 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: VDS_AUTO_FENCE_STATUS_FAILED not exist in string table >2014-07-01 04:10:20,244 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: VDS_AUTO_FENCE_FAILED_CALL_FENCE_SPM not exist in string table >2014-07-01 04:10:20,245 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: VDS_HIGH_NETWORK_USE not exist in string table >2014-07-01 04:10:20,246 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: USER_FAILED_REMOVE_VM not exist in string table >2014-07-01 04:10:20,247 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: USER_RUN_UNLOCK_ENTITY_SCRIPT not exist in string table >2014-07-01 04:10:20,248 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: VDS_NETWORK_MTU_DIFFER_FROM_LOGICAL_NETWORK not exist in string table >2014-07-01 04:10:20,249 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: VDS_HOST_IN_CONNECTING_STATE not exist in string table >2014-07-01 04:10:20,249 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: STORAGE_ACTIVATE_ASYNC not exist in string table >2014-07-01 04:10:20,250 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: DWH_STOPPED not exist in string table >2014-07-01 04:10:20,250 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: DWH_STARTED not exist in string table >2014-07-01 04:10:20,251 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: DWH_ERROR not exist in string table >2014-07-01 04:10:20,252 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: USER_REMOVE_AUDIT_LOG not exist in string table >2014-07-01 04:10:20,252 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: USER_REMOVE_AUDIT_LOG_FAILED not exist in string table >2014-07-01 04:10:20,253 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: USER_CLEAR_ALL_DISMISSED_AUDIT_LOG not exist in string table >2014-07-01 04:10:20,253 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) AuditLogType: USER_CLEAR_ALL_DISMISSED_AUDIT_LOG_FAILED not exist in string table >2014-07-01 04:10:20,488 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin logged in. >2014-07-01 04:10:25,722 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-3) Running command: LoginUserCommand internal: false. >2014-07-01 04:10:25,737 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin logged in. >2014-07-01 04:22:17,991 INFO [org.ovirt.engine.core.bll.AddVdsCommand] (ajp--127.0.0.1-8702-12) [7bdc62ed] Running command: AddVdsCommand internal: false. Entities affected : ID: 00000001-0001-0001-0001-00000000034a Type: VdsGroups >2014-07-01 04:22:18,094 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp--127.0.0.1-8702-12) [6b749fd0] Running command: AddVdsSpmIdCommand internal: true. Entities affected : ID: 9db8ad35-3d6f-4990-9d4f-f90e96c20843 Type: VDS >2014-07-01 04:22:18,115 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp--127.0.0.1-8702-12) [6b749fd0] Lock freed to object EngineLock [exclusiveLocks= key: 00000002-0002-0002-0002-000000000251 value: REGISTER_VDS >, sharedLocks= ] >2014-07-01 04:22:18,121 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp--127.0.0.1-8702-12) [6b749fd0] START, RemoveVdsVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 9db8ad35-3d6f-4990-9d4f-f90e96c20843), log id: 4ffa48c >2014-07-01 04:22:18,121 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp--127.0.0.1-8702-12) [6b749fd0] FINISH, RemoveVdsVDSCommand, log id: 4ffa48c >2014-07-01 04:22:18,127 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-12) [6b749fd0] START, AddVdsVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 9db8ad35-3d6f-4990-9d4f-f90e96c20843), log id: 36af4505 >2014-07-01 04:22:18,131 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-12) [6b749fd0] AddVds - entered , starting logic to add VDS 9db8ad35-3d6f-4990-9d4f-f90e96c20843 >2014-07-01 04:22:18,134 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-12) [6b749fd0] AddVds - VDS 9db8ad35-3d6f-4990-9d4f-f90e96c20843 was added, will try to add it to the resource manager >2014-07-01 04:22:18,138 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp--127.0.0.1-8702-12) [6b749fd0] Entered VdsManager constructor >2014-07-01 04:22:18,167 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp--127.0.0.1-8702-12) [6b749fd0] Initialize vdsBroker (orange-vdsd.qa.lab.tlv.redhat.com,54,321) >2014-07-01 04:22:18,313 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp--127.0.0.1-8702-12) [6b749fd0] VDS 9db8ad35-3d6f-4990-9d4f-f90e96c20843 was added to the Resource Manager >2014-07-01 04:22:18,316 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-12) [6b749fd0] FINISH, AddVdsVDSCommand, log id: 36af4505 >2014-07-01 04:22:18,330 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-12) [6b749fd0] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host orange-vdsd.qa.lab.tlv.redhat.com. >2014-07-01 04:22:18,360 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-12) [6b749fd0] Correlation ID: 7bdc62ed, Job ID: 60af66f2-5520-41bd-9332-ac0a6a020d6f, Call Stack: null, Custom Event ID: -1, Message: Host orange-vdsd.qa.lab.tlv.redhat.com was added by admin. >2014-07-01 04:22:18,377 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-4) [43ff3e4] Lock Acquired to object EngineLock [exclusiveLocks= key: 9db8ad35-3d6f-4990-9d4f-f90e96c20843 value: VDS >, sharedLocks= ] >2014-07-01 04:22:18,389 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-4) [43ff3e4] Running command: InstallVdsInternalCommand internal: true. Entities affected : ID: 9db8ad35-3d6f-4990-9d4f-f90e96c20843 Type: VDS >2014-07-01 04:22:18,400 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-4) [43ff3e4] Before Installation host 9db8ad35-3d6f-4990-9d4f-f90e96c20843, orange-vdsd.qa.lab.tlv.redhat.com >2014-07-01 04:22:18,407 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-4) [43ff3e4] START, SetVdsStatusVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 9db8ad35-3d6f-4990-9d4f-f90e96c20843, status=Installing, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 12ed4757 >2014-07-01 04:22:18,427 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-4) [43ff3e4] FINISH, SetVdsStatusVDSCommand, log id: 12ed4757 >2014-07-01 04:22:18,507 ERROR [org.ovirt.engine.core.bll.VdsDeploy] (org.ovirt.thread.pool-8-thread-4) [43ff3e4] Error during host orange-vdsd.qa.lab.tlv.redhat.com install: java.io.IOException: SSH session closed during connection 'root@orange-vdsd.qa.lab.tlv.redhat.com' > at org.ovirt.engine.core.uutils.ssh.SSHClient.connect(SSHClient.java:309) [uutils.jar:] > at org.ovirt.engine.core.bll.utils.EngineSSHClient.connect(EngineSSHClient.java:59) [bll.jar:] > at org.ovirt.engine.core.uutils.ssh.SSHDialog.connect(SSHDialog.java:249) [uutils.jar:] > at org.ovirt.engine.core.bll.VdsDeploy.execute(VdsDeploy.java:1080) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsInternalCommand.installHost(InstallVdsInternalCommand.java:146) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsInternalCommand.executeCommand(InstallVdsInternalCommand.java:73) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1135) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1253) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1844) [bll.jar:] > at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:174) [utils.jar:] > at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:116) [utils.jar:] > at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1274) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:343) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runAction(Backend.java:430) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:411) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runInternalAction(Backend.java:616) [bll.jar:] > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_55] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_55] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_55] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_55] > at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:114) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:125) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:135) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:82) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:211) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:363) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:194) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:173) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.ovirt.engine.core.bll.interfaces.BackendInternal$$$view7.runInternalAction(Unknown Source) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInternalAction(CommandBase.java:2141) [bll.jar:] > at org.ovirt.engine.core.bll.AddVdsCommand$3.run(AddVdsCommand.java:189) [bll.jar:] > at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:90) [utils.jar:] > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_55] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_55] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_55] > >2014-07-01 04:22:18,538 ERROR [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-4) [43ff3e4] Host installation failed for host 9db8ad35-3d6f-4990-9d4f-f90e96c20843, orange-vdsd.qa.lab.tlv.redhat.com.: java.io.IOException: SSH session closed during connection 'root@orange-vdsd.qa.lab.tlv.redhat.com' > at org.ovirt.engine.core.uutils.ssh.SSHClient.connect(SSHClient.java:309) [uutils.jar:] > at org.ovirt.engine.core.bll.utils.EngineSSHClient.connect(EngineSSHClient.java:59) [bll.jar:] > at org.ovirt.engine.core.uutils.ssh.SSHDialog.connect(SSHDialog.java:249) [uutils.jar:] > at org.ovirt.engine.core.bll.VdsDeploy.execute(VdsDeploy.java:1080) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsInternalCommand.installHost(InstallVdsInternalCommand.java:146) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsInternalCommand.executeCommand(InstallVdsInternalCommand.java:73) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1135) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1253) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1844) [bll.jar:] > at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:174) [utils.jar:] > at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:116) [utils.jar:] > at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1274) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:343) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runAction(Backend.java:430) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:411) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runInternalAction(Backend.java:616) [bll.jar:] > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_55] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_55] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_55] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_55] > at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:114) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:125) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:135) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:82) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:211) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:363) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:194) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:173) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.ovirt.engine.core.bll.interfaces.BackendInternal$$$view7.runInternalAction(Unknown Source) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInternalAction(CommandBase.java:2141) [bll.jar:] > at org.ovirt.engine.core.bll.AddVdsCommand$3.run(AddVdsCommand.java:189) [bll.jar:] > at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:90) [utils.jar:] > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_55] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_55] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_55] > >2014-07-01 04:22:18,576 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-4) [43ff3e4] START, SetVdsStatusVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 9db8ad35-3d6f-4990-9d4f-f90e96c20843, status=InstallFailed, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 7b547fd4 >2014-07-01 04:22:18,590 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-4) [43ff3e4] FINISH, SetVdsStatusVDSCommand, log id: 7b547fd4 >2014-07-01 04:22:18,607 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-4) [43ff3e4] Correlation ID: 43ff3e4, Job ID: 60af66f2-5520-41bd-9332-ac0a6a020d6f, Call Stack: null, Custom Event ID: -1, Message: Host orange-vdsd.qa.lab.tlv.redhat.com installation failed. SSH session closed during connection 'root@orange-vdsd.qa.lab.tlv.redhat.com'. >2014-07-01 04:22:18,623 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-4) [43ff3e4] Lock freed to object EngineLock [exclusiveLocks= key: 9db8ad35-3d6f-4990-9d4f-f90e96c20843 value: VDS >, sharedLocks= ] >2014-07-01 04:22:21,340 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-69) Initializing Host: orange-vdsd.qa.lab.tlv.redhat.com >2014-07-01 04:29:44,986 WARN [org.ovirt.engine.core.bll.MaintenanceNumberOfVdssCommand] (ajp--127.0.0.1-8702-6) [36a64003] CanDoAction of action MaintenanceNumberOfVdss failed. Reasons:VAR__TYPE__HOST,VAR__ACTION__MAINTENANCE,VDS_CANNOT_MAINTENANCE_VDS_IS_NOT_OPERATIONAL >2014-07-01 04:31:23,412 INFO [org.ovirt.engine.core.bll.RemoveVdsCommand] (ajp--127.0.0.1-8702-8) [e718360] Lock Acquired to object EngineLock [exclusiveLocks= key: 9db8ad35-3d6f-4990-9d4f-f90e96c20843 value: VDS >, sharedLocks= ] >2014-07-01 04:31:23,424 INFO [org.ovirt.engine.core.bll.RemoveVdsCommand] (org.ovirt.thread.pool-8-thread-6) [e718360] Running command: RemoveVdsCommand internal: false. Entities affected : ID: 9db8ad35-3d6f-4990-9d4f-f90e96c20843 Type: VDS >2014-07-01 04:31:23,466 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (org.ovirt.thread.pool-8-thread-6) [e718360] START, RemoveVdsVDSCommand( HostId = 9db8ad35-3d6f-4990-9d4f-f90e96c20843), log id: 44d1862e >2014-07-01 04:31:23,467 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (org.ovirt.thread.pool-8-thread-6) [e718360] vdsManager::disposing >2014-07-01 04:31:23,468 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (org.ovirt.thread.pool-8-thread-6) [e718360] FINISH, RemoveVdsVDSCommand, log id: 44d1862e >2014-07-01 04:31:23,476 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-6) [e718360] Correlation ID: e718360, Call Stack: null, Custom Event ID: -1, Message: Host orange-vdsd.qa.lab.tlv.redhat.com was removed by admin. >2014-07-01 04:31:23,476 INFO [org.ovirt.engine.core.bll.RemoveVdsCommand] (org.ovirt.thread.pool-8-thread-6) [e718360] Lock freed to object EngineLock [exclusiveLocks= key: 9db8ad35-3d6f-4990-9d4f-f90e96c20843 value: VDS >, sharedLocks= ] >2014-07-01 04:31:43,984 INFO [org.ovirt.engine.core.bll.AddVdsCommand] (ajp--127.0.0.1-8702-7) [5452d5b8] Running command: AddVdsCommand internal: false. Entities affected : ID: 00000001-0001-0001-0001-00000000034a Type: VdsGroups >2014-07-01 04:31:44,005 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp--127.0.0.1-8702-7) [219ad58] Running command: AddVdsSpmIdCommand internal: true. Entities affected : ID: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 Type: VDS >2014-07-01 04:31:44,013 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp--127.0.0.1-8702-7) [219ad58] Lock freed to object EngineLock [exclusiveLocks= key: 00000002-0002-0002-0002-000000000251 value: REGISTER_VDS >, sharedLocks= ] >2014-07-01 04:31:44,015 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp--127.0.0.1-8702-7) [219ad58] START, RemoveVdsVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6), log id: 3910077e >2014-07-01 04:31:44,015 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp--127.0.0.1-8702-7) [219ad58] FINISH, RemoveVdsVDSCommand, log id: 3910077e >2014-07-01 04:31:44,020 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-7) [219ad58] START, AddVdsVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6), log id: f3d1c28 >2014-07-01 04:31:44,021 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-7) [219ad58] AddVds - entered , starting logic to add VDS 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 >2014-07-01 04:31:44,022 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-7) [219ad58] AddVds - VDS 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 was added, will try to add it to the resource manager >2014-07-01 04:31:44,022 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp--127.0.0.1-8702-7) [219ad58] Entered VdsManager constructor >2014-07-01 04:31:44,044 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp--127.0.0.1-8702-7) [219ad58] Initialize vdsBroker (orange-vdsd.qa.lab.tlv.redhat.com,54,321) >2014-07-01 04:31:44,049 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp--127.0.0.1-8702-7) [219ad58] VDS 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 was added to the Resource Manager >2014-07-01 04:31:44,050 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp--127.0.0.1-8702-7) [219ad58] FINISH, AddVdsVDSCommand, log id: f3d1c28 >2014-07-01 04:31:44,056 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) [219ad58] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host orange-vdsd.qa.lab.tlv.redhat.com. >2014-07-01 04:31:44,067 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) [219ad58] Correlation ID: 5452d5b8, Job ID: c4fd975a-bbe1-4aad-b49a-714e499b5f42, Call Stack: null, Custom Event ID: -1, Message: Host orange-vdsd.qa.lab.tlv.redhat.com was added by admin. >2014-07-01 04:31:44,076 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] Lock Acquired to object EngineLock [exclusiveLocks= key: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 value: VDS >, sharedLocks= ] >2014-07-01 04:31:44,084 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] Running command: InstallVdsInternalCommand internal: true. Entities affected : ID: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 Type: VDS >2014-07-01 04:31:44,084 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] Before Installation host 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, orange-vdsd.qa.lab.tlv.redhat.com >2014-07-01 04:31:44,091 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] START, SetVdsStatusVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, status=Installing, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 722a5649 >2014-07-01 04:31:44,100 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] FINISH, SetVdsStatusVDSCommand, log id: 722a5649 >2014-07-01 04:31:44,156 INFO [org.ovirt.engine.core.bll.InstallerMessages] (org.ovirt.thread.pool-8-thread-7) [1950573c] Installation orange-vdsd.qa.lab.tlv.redhat.com: Connected to host orange-vdsd.qa.lab.tlv.redhat.com with SSH key fingerprint: 77:2d:35:dd:f2:f8:81:70:b1:a1:b6:1d:07:bb:71:f2 >2014-07-01 04:31:44,170 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-7) [1950573c] Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Connected to host orange-vdsd.qa.lab.tlv.redhat.com with SSH key fingerprint: 77:2d:35:dd:f2:f8:81:70:b1:a1:b6:1d:07:bb:71:f2. >2014-07-01 04:31:44,231 INFO [org.ovirt.engine.core.bll.VdsDeploy] (org.ovirt.thread.pool-8-thread-7) [1950573c] Installation of orange-vdsd.qa.lab.tlv.redhat.com. Executing command via SSH umask 0077; MYTMP="$(TMPDIR="${OVIRT_TMPDIR}" 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}" && tar --warning=no-timestamp -C "${MYTMP}" -x && "${MYTMP}"/setup DIALOG/dialect=str:machine DIALOG/customization=bool:True < /var/cache/ovirt-engine/ovirt-host-deploy.tar >2014-07-01 04:31:44,232 INFO [org.ovirt.engine.core.utils.archivers.tar.CachedTar] (org.ovirt.thread.pool-8-thread-7) Tarball '/var/cache/ovirt-engine/ovirt-host-deploy.tar' is missing, creating >2014-07-01 04:31:44,381 INFO [org.ovirt.engine.core.uutils.ssh.SSHDialog] (org.ovirt.thread.pool-8-thread-7) SSH execute root@orange-vdsd.qa.lab.tlv.redhat.com 'umask 0077; MYTMP="$(TMPDIR="${OVIRT_TMPDIR}" 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}" && tar --warning=no-timestamp -C "${MYTMP}" -x && "${MYTMP}"/setup DIALOG/dialect=str:machine DIALOG/customization=bool:True' >2014-07-01 04:31:46,418 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Stage: Initializing >2014-07-01 04:31:46,422 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Stage: Initializing. >2014-07-01 04:31:46,450 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Stage: Environment setup >2014-07-01 04:31:46,458 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Stage: Environment setup. >2014-07-01 04:31:46,481 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Stage: Environment packages setup >2014-07-01 04:31:46,485 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Stage: Environment packages setup. >2014-07-01 04:31:47,051 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-86) Initializing Host: orange-vdsd.qa.lab.tlv.redhat.com >2014-07-01 04:31:58,160 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Stage: Programs detection >2014-07-01 04:31:58,164 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Stage: Programs detection. >2014-07-01 04:31:58,171 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Stage: Environment customization >2014-07-01 04:31:58,174 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Stage: Environment customization. >2014-07-01 04:31:59,607 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Kdump unsupported >2014-07-01 04:31:59,616 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Kdump unsupported. >2014-07-01 04:31:59,631 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Logs at host located at: '/tmp/ovirt-host-deploy-20140701123145-76tiae.log' >2014-07-01 04:31:59,639 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Logs at host located at: '/tmp/ovirt-host-deploy-20140701123145-76tiae.log'. >2014-07-01 04:31:59,700 INFO [org.ovirt.engine.core.bll.VdsDeploy] (VdsDeploy) Host orange-vdsd.qa.lab.tlv.redhat.com reports unique id 2E7B3537-A079-0010-955F-0016367B3E87 >2014-07-01 04:31:59,726 INFO [org.ovirt.engine.core.bll.VdsDeploy] (VdsDeploy) Assigning unique id 2E7B3537-A079-0010-955F-0016367B3E87 to Host orange-vdsd.qa.lab.tlv.redhat.com >2014-07-01 04:31:59,843 WARN [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Kdump detection is not supported by host! >2014-07-01 04:31:59,847 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Host orange-vdsd.qa.lab.tlv.redhat.com installation in progress . Kdump detection is not supported by host!. >2014-07-01 04:31:59,873 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Stage: Setup validation >2014-07-01 04:31:59,877 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Stage: Setup validation. >2014-07-01 04:31:59,953 WARN [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Machine does not support constant timestamp counter, this may effect performance >2014-07-01 04:31:59,959 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Host orange-vdsd.qa.lab.tlv.redhat.com installation in progress . Machine does not support constant timestamp counter, this may effect performance. >2014-07-01 04:31:59,979 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Hardware supports virtualization >2014-07-01 04:31:59,985 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Hardware supports virtualization. >2014-07-01 04:32:00,639 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Stage: Transaction setup >2014-07-01 04:32:00,646 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Stage: Transaction setup. >2014-07-01 04:32:00,731 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Stage: Misc configuration >2014-07-01 04:32:00,736 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Stage: Misc configuration. >2014-07-01 04:32:00,737 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Stage: Package installation >2014-07-01 04:32:00,740 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Stage: Package installation. >2014-07-01 04:32:10,323 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Stage: Misc configuration >2014-07-01 04:32:10,329 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Stage: Misc configuration. >2014-07-01 04:32:12,991 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Enrolling certificate >2014-07-01 04:32:12,999 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Enrolling certificate. >2014-07-01 04:32:14,468 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Stage: Transaction commit >2014-07-01 04:32:14,475 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Stage: Transaction commit. >2014-07-01 04:32:14,615 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Stage: Closing up >2014-07-01 04:32:14,619 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Stage: Closing up. >2014-07-01 04:32:20,211 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Stopping libvirtd >2014-07-01 04:32:20,219 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Stopping libvirtd. >2014-07-01 04:32:21,143 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Starting vdsm >2014-07-01 04:32:21,148 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Starting vdsm. >2014-07-01 04:32:26,341 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Stage: Pre-termination >2014-07-01 04:32:26,365 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Stage: Pre-termination. >2014-07-01 04:32:26,377 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Retrieving installation logs to: '/var/log/ovirt-engine/host-deploy/ovirt-20140701043226-orange-vdsd.qa.lab.tlv.redhat.com-1950573c.log' >2014-07-01 04:32:26,381 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Retrieving installation logs to: '/var/log/ovirt-engine/host-deploy/ovirt-20140701043226-orange-vdsd.qa.lab.tlv.redhat.com-1950573c.log'. >2014-07-01 04:32:26,851 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation orange-vdsd.qa.lab.tlv.redhat.com: Stage: Termination >2014-07-01 04:32:26,858 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 1950573c, Call Stack: null, Custom Event ID: -1, Message: Installing Host orange-vdsd.qa.lab.tlv.redhat.com. Stage: Termination. >2014-07-01 04:32:27,152 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.PollVDSCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException >2014-07-01 04:32:27,164 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.PollVDSCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] Command PollVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6) execution failed. Exception: RuntimeException: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException >2014-07-01 04:32:27,668 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.PollVDSCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException >2014-07-01 04:32:27,673 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.PollVDSCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] Command PollVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6) execution failed. Exception: RuntimeException: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException >2014-07-01 04:32:28,445 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.PollVDSCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] org.ovirt.engine.core.vdsbroker.vdsbroker.VDSRecoveringException: Recovering from crash or Initializing >2014-07-01 04:32:28,446 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.PollVDSCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] Command PollVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6) execution failed. Exception: VDSRecoveringException: Recovering from crash or Initializing >2014-07-01 04:32:28,972 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.PollVDSCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] org.ovirt.engine.core.vdsbroker.vdsbroker.VDSRecoveringException: Recovering from crash or Initializing >2014-07-01 04:32:28,973 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.PollVDSCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] Command PollVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6) execution failed. Exception: VDSRecoveringException: Recovering from crash or Initializing >2014-07-01 04:32:29,490 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.PollVDSCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] org.ovirt.engine.core.vdsbroker.vdsbroker.VDSRecoveringException: Recovering from crash or Initializing >2014-07-01 04:32:29,491 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.PollVDSCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] Command PollVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6) execution failed. Exception: VDSRecoveringException: Recovering from crash or Initializing >2014-07-01 04:32:30,003 INFO [org.ovirt.engine.core.bll.network.NetworkConfigurator] (org.ovirt.thread.pool-8-thread-7) [1950573c] Engine managed to communicate with VDSM agent on host orange-vdsd.qa.lab.tlv.redhat.com >2014-07-01 04:32:30,491 INFO [org.ovirt.engine.core.bll.network.NetworkConfigurator] (org.ovirt.thread.pool-8-thread-7) [1950573c] The management network ovirtmgmt is already configured on host orange-vdsd.qa.lab.tlv.redhat.com >2014-07-01 04:32:30,494 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] START, SetVdsStatusVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, status=Initializing, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 2562e306 >2014-07-01 04:32:30,501 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] FINISH, SetVdsStatusVDSCommand, log id: 2562e306 >2014-07-01 04:32:30,501 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] After Installation host orange-vdsd.qa.lab.tlv.redhat.com, VDS >2014-07-01 04:32:30,506 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-7) [1950573c] Correlation ID: 1950573c, Job ID: c4fd975a-bbe1-4aad-b49a-714e499b5f42, Call Stack: null, Custom Event ID: -1, Message: Host orange-vdsd.qa.lab.tlv.redhat.com installed >2014-07-01 04:32:30,514 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-7) [1950573c] Lock freed to object EngineLock [exclusiveLocks= key: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 value: VDS >, sharedLocks= ] >2014-07-01 04:32:32,293 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-89) START, GetHardwareInfoVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vds=Host[orange-vdsd.qa.lab.tlv.redhat.com,8749c6c4-ffd0-4f2d-a877-09a832e7a8f6]), log id: 7ef7c849 >2014-07-01 04:32:32,413 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-89) FINISH, GetHardwareInfoVDSCommand, log id: 7ef7c849 >2014-07-01 04:32:32,506 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (DefaultQuartzScheduler_Worker-89) [4e4e0c5f] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 Type: VDS >2014-07-01 04:32:32,632 INFO [org.ovirt.engine.core.bll.UpdateVdsGroupCommand] (DefaultQuartzScheduler_Worker-89) [74bedaf] Running command: UpdateVdsGroupCommand internal: true. Entities affected : ID: 00000001-0001-0001-0001-00000000034a Type: VdsGroups >2014-07-01 04:32:32,659 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-89) [74bedaf] Correlation ID: 74bedaf, Call Stack: null, Custom Event ID: -1, Message: Host cluster Default was updated by system >2014-07-01 04:32:32,807 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-89) [74bedaf] START, GetHardwareInfoVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vds=Host[orange-vdsd.qa.lab.tlv.redhat.com,8749c6c4-ffd0-4f2d-a877-09a832e7a8f6]), log id: 5d8c801b >2014-07-01 04:32:32,863 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-89) [74bedaf] FINISH, GetHardwareInfoVDSCommand, log id: 5d8c801b >2014-07-01 04:32:32,924 INFO [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (DefaultQuartzScheduler_Worker-89) [50ec2714] Running command: InitVdsOnUpCommand internal: true. Entities affected : ID: 00000002-0002-0002-0002-000000000251 Type: StoragePool >2014-07-01 04:32:32,940 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-89) [50ec2714] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Kdump detection is enabled for host 'orange-vdsd.qa.lab.tlv.redhat.com', but kdump is not configured properly on host. >2014-07-01 04:32:32,950 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (DefaultQuartzScheduler_Worker-89) [50ec2714] START, SetMOMPolicyParametersVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6), log id: 4c8dc9cc >2014-07-01 04:32:33,087 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (DefaultQuartzScheduler_Worker-89) [50ec2714] FINISH, SetMOMPolicyParametersVDSCommand, log id: 4c8dc9cc >2014-07-01 04:32:33,092 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-89) [50ec2714] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Status of host orange-vdsd.qa.lab.tlv.redhat.com was set to Up. >2014-07-01 04:32:33,098 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-89) [50ec2714] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host orange-vdsd.qa.lab.tlv.redhat.com. >2014-07-01 04:32:33,207 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (DefaultQuartzScheduler_Worker-89) [51002013] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 Type: VDS >2014-07-01 04:32:33,216 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (DefaultQuartzScheduler_Worker-89) [a6bda6b] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 Type: VDS >2014-07-01 04:35:14,430 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (ajp--127.0.0.1-8702-9) [6833fc0b] Lock Acquired to object EngineLock [exclusiveLocks= key: 10.35.160.108:/RHEV/myakove_testday_ovirt value: STORAGE_CONNECTION >, sharedLocks= ] >2014-07-01 04:35:14,444 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (ajp--127.0.0.1-8702-9) [6833fc0b] Running command: AddStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-01 04:35:14,449 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-9) [6833fc0b] START, ConnectStorageServerVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.35.160.108:/RHEV/myakove_testday_ovirt, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 472993ed >2014-07-01 04:35:14,991 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-9) [6833fc0b] FINISH, ConnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 472993ed >2014-07-01 04:35:15,007 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (ajp--127.0.0.1-8702-9) [6833fc0b] Lock freed to object EngineLock [exclusiveLocks= key: 10.35.160.108:/RHEV/myakove_testday_ovirt value: STORAGE_CONNECTION >, sharedLocks= ] >2014-07-01 04:35:15,143 INFO [org.ovirt.engine.core.bll.storage.AddNFSStorageDomainCommand] (ajp--127.0.0.1-8702-4) [583043c5] Running command: AddNFSStorageDomainCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-01 04:35:15,162 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-4) [583043c5] START, ConnectStorageServerVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 1214142a-5bc0-4826-a004-d45481505028, connection: 10.35.160.108:/RHEV/myakove_testday_ovirt, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 7a79cad4 >2014-07-01 04:35:15,178 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-4) [583043c5] FINISH, ConnectStorageServerVDSCommand, return: {1214142a-5bc0-4826-a004-d45481505028=0}, log id: 7a79cad4 >2014-07-01 04:35:15,180 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (ajp--127.0.0.1-8702-4) [583043c5] START, CreateStorageDomainVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, storageDomain=org.ovirt.engine.core.common.businessentities.StorageDomainStatic@e4e73cc6, args=10.35.160.108:/RHEV/myakove_testday_ovirt), log id: 4e8172c0 >2014-07-01 04:35:17,152 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (ajp--127.0.0.1-8702-4) [583043c5] FINISH, CreateStorageDomainVDSCommand, log id: 4e8172c0 >2014-07-01 04:35:17,154 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (ajp--127.0.0.1-8702-4) [583043c5] START, GetStorageDomainStatsVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, storageDomainId=86ecda08-9abd-4a3c-b13f-de0f3746ce82), log id: 5325e277 >2014-07-01 04:35:17,210 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (ajp--127.0.0.1-8702-4) [583043c5] FINISH, GetStorageDomainStatsVDSCommand, return: org.ovirt.engine.core.common.businessentities.StorageDomain@e7454112, log id: 5325e277 >2014-07-01 04:35:17,275 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) [583043c5] Correlation ID: 583043c5, Job ID: fc9a0cd0-aae5-498c-85b0-2e5421c0053a, Call Stack: null, Custom Event ID: -1, Message: Storage Domain nfs_td was added by admin >2014-07-01 04:35:17,369 INFO [org.ovirt.engine.core.bll.storage.DisconnectStorageServerConnectionCommand] (ajp--127.0.0.1-8702-5) [7d5a6d23] Running command: DisconnectStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-01 04:35:17,379 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-5) [7d5a6d23] START, DisconnectStorageServerVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 1214142a-5bc0-4826-a004-d45481505028, connection: 10.35.160.108:/RHEV/myakove_testday_ovirt, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: fa5c812 >2014-07-01 04:35:17,591 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-5) [7d5a6d23] FINISH, DisconnectStorageServerVDSCommand, return: {1214142a-5bc0-4826-a004-d45481505028=0}, log id: fa5c812 >2014-07-01 04:35:17,766 INFO [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] (ajp--127.0.0.1-8702-7) [31abc0f] Running command: AttachStorageDomainToPoolCommand internal: false. Entities affected : ID: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 Type: Storage >2014-07-01 04:35:17,785 INFO [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (ajp--127.0.0.1-8702-7) [28c763c9] Lock Acquired to object EngineLock [exclusiveLocks= key: 00000002-0002-0002-0002-000000000251 value: POOL >, sharedLocks= ] >2014-07-01 04:35:17,807 INFO [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (ajp--127.0.0.1-8702-7) [28c763c9] Running command: AddStoragePoolWithStoragesCommand internal: true. Entities affected : ID: 00000002-0002-0002-0002-000000000251 Type: StoragePool >2014-07-01 04:35:17,952 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (ajp--127.0.0.1-8702-7) [4e3f3e6] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-01 04:35:17,966 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-7) [4e3f3e6] START, ConnectStorageServerVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 1214142a-5bc0-4826-a004-d45481505028, connection: 10.35.160.108:/RHEV/myakove_testday_ovirt, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 6c14819f >2014-07-01 04:35:18,081 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--127.0.0.1-8702-7) [4e3f3e6] FINISH, ConnectStorageServerVDSCommand, return: {1214142a-5bc0-4826-a004-d45481505028=0}, log id: 6c14819f >2014-07-01 04:35:18,087 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStoragePoolVDSCommand] (ajp--127.0.0.1-8702-7) [4e3f3e6] START, CreateStoragePoolVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, storagePoolId=00000002-0002-0002-0002-000000000251, storagePoolName=Default, masterDomainId=86ecda08-9abd-4a3c-b13f-de0f3746ce82, domainsIdList=[86ecda08-9abd-4a3c-b13f-de0f3746ce82], masterVersion=1), log id: 351c4bf0 >2014-07-01 04:35:41,923 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStoragePoolVDSCommand] (ajp--127.0.0.1-8702-7) [4e3f3e6] FINISH, CreateStoragePoolVDSCommand, log id: 351c4bf0 >2014-07-01 04:35:41,929 INFO [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (ajp--127.0.0.1-8702-7) [4e3f3e6] Lock freed to object EngineLock [exclusiveLocks= key: 00000002-0002-0002-0002-000000000251 value: POOL >, sharedLocks= ] >2014-07-01 04:35:41,935 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-7) [2a0ab4cb] Lock Acquired to object EngineLock [exclusiveLocks= key: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 value: STORAGE >, sharedLocks= ] >2014-07-01 04:35:41,939 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-7) [2a0ab4cb] Running command: ActivateStorageDomainCommand internal: true. Entities affected : ID: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 Type: Storage >2014-07-01 04:35:41,956 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-7) [2a0ab4cb] Lock freed to object EngineLock [exclusiveLocks= key: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 value: STORAGE >, sharedLocks= ] >2014-07-01 04:35:41,959 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-7) [2a0ab4cb] ActivateStorage Domain. Before Connect all hosts to pool. Time:7/1/14 4:35 AM >2014-07-01 04:35:41,963 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (org.ovirt.thread.pool-8-thread-16) [70aec99f] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-01 04:35:41,973 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-16) [70aec99f] START, ConnectStorageServerVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 1214142a-5bc0-4826-a004-d45481505028, connection: 10.35.160.108:/RHEV/myakove_testday_ovirt, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 16a52f1e >2014-07-01 04:35:41,996 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-16) [70aec99f] FINISH, ConnectStorageServerVDSCommand, return: {1214142a-5bc0-4826-a004-d45481505028=0}, log id: 16a52f1e >2014-07-01 04:35:41,999 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (ajp--127.0.0.1-8702-7) [2a0ab4cb] START, ActivateStorageDomainVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false, storageDomainId = 86ecda08-9abd-4a3c-b13f-de0f3746ce82), log id: 349f8408 >2014-07-01 04:35:42,023 INFO [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (ajp--127.0.0.1-8702-7) [4be33387] Running command: SetStoragePoolStatusCommand internal: true. Entities affected : ID: 00000002-0002-0002-0002-000000000251 Type: StoragePool >2014-07-01 04:35:42,035 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) [4be33387] Correlation ID: 4be33387, Call Stack: null, Custom Event ID: -1, Message: Data Center is being initialized, please wait for initialization to complete. >2014-07-01 04:35:42,051 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (ajp--127.0.0.1-8702-7) [4be33387] hostFromVds::selectedVds - orange-vdsd.qa.lab.tlv.redhat.com, spmStatus Unknown_Pool, storage pool Default >2014-07-01 04:35:42,059 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (ajp--127.0.0.1-8702-7) [4be33387] START, ConnectStoragePoolVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vdsId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, storagePoolId = 00000002-0002-0002-0002-000000000251, masterVersion = 1), log id: 2e7a5a8f >2014-07-01 04:35:42,998 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (ajp--127.0.0.1-8702-7) [4be33387] FINISH, ConnectStoragePoolVDSCommand, log id: 2e7a5a8f >2014-07-01 04:35:43,061 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (ajp--127.0.0.1-8702-7) [4be33387] hostFromVds::Connected host to pool - selectedVds - orange-vdsd.qa.lab.tlv.redhat.com, spmStatus Free, storage pool Default >2014-07-01 04:35:43,071 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (ajp--127.0.0.1-8702-7) [4be33387] starting spm on vds orange-vdsd.qa.lab.tlv.redhat.com, storage pool Default, prevId -1, LVER -1 >2014-07-01 04:35:43,073 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp--127.0.0.1-8702-7) [4be33387] START, SpmStartVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, storagePoolId = 00000002-0002-0002-0002-000000000251, prevId=-1, prevLVER=-1, storagePoolFormatType=V3, recoveryMode=Manual, SCSIFencing=false), log id: 3639a7e3 >2014-07-01 04:35:43,134 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp--127.0.0.1-8702-7) [4be33387] spmStart polling started: taskId = 3feb5f99-88f2-4ff1-a3e8-997736ca545b >2014-07-01 04:36:04,403 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp--127.0.0.1-8702-7) [4be33387] spmStart polling ended: taskId = 3feb5f99-88f2-4ff1-a3e8-997736ca545b task status = finished >2014-07-01 04:36:04,470 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp--127.0.0.1-8702-7) [4be33387] spmStart polling ended, spm status: SPM >2014-07-01 04:36:04,471 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (ajp--127.0.0.1-8702-7) [4be33387] START, HSMClearTaskVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, taskId=3feb5f99-88f2-4ff1-a3e8-997736ca545b), log id: 13955c1c >2014-07-01 04:36:04,537 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (ajp--127.0.0.1-8702-7) [4be33387] FINISH, HSMClearTaskVDSCommand, log id: 13955c1c >2014-07-01 04:36:04,538 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp--127.0.0.1-8702-7) [4be33387] FINISH, SpmStartVDSCommand, return: org.ovirt.engine.core.common.businessentities.SpmStatusResult@3a11867f, log id: 3639a7e3 >2014-07-01 04:36:04,541 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (ajp--127.0.0.1-8702-7) [4be33387] Initialize Irs proxy from vds: orange-vdsd.qa.lab.tlv.redhat.com >2014-07-01 04:36:04,546 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) [4be33387] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Storage Pool Manager runs on Host orange-vdsd.qa.lab.tlv.redhat.com (Address: orange-vdsd.qa.lab.tlv.redhat.com). >2014-07-01 04:36:04,630 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (ajp--127.0.0.1-8702-7) [4be33387] FINISH, ActivateStorageDomainVDSCommand, log id: 349f8408 >2014-07-01 04:36:04,631 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-7) [4be33387] ActivateStorage Domain. After Connect all hosts to pool. Time:7/1/14 4:36 AM >2014-07-01 04:36:04,651 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-8-thread-15) START, ConnectStoragePoolVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vdsId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, storagePoolId = 00000002-0002-0002-0002-000000000251, masterVersion = 1), log id: 45c31f3c >2014-07-01 04:36:05,325 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-8-thread-15) FINISH, ConnectStoragePoolVDSCommand, log id: 45c31f3c >2014-07-01 04:36:05,325 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp--127.0.0.1-8702-7) [4be33387] ActivateStorage Domain. After change storage pool status in vds. Time:7/1/14 4:36 AM >2014-07-01 04:36:05,336 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) [4be33387] Correlation ID: 2a0ab4cb, Call Stack: null, Custom Event ID: -1, Message: Storage Domain nfs_td (Data Center Default) was activated by admin >2014-07-01 04:36:05,347 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) [4be33387] Correlation ID: 28c763c9, Job ID: 042443e5-8eb6-4551-82ef-8f0e99788c6f, Call Stack: null, Custom Event ID: -1, Message: Storage Domains were attached to Data Center Default by admin >2014-07-01 04:36:05,358 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-7) [4be33387] Correlation ID: 31abc0f, Job ID: 042443e5-8eb6-4551-82ef-8f0e99788c6f, Call Stack: null, Custom Event ID: -1, Message: Storage Domain nfs_td was attached to Data Center Default by admin >2014-07-01 04:41:04,498 INFO [org.ovirt.engine.core.bll.network.host.SetupNetworksCommand] (ajp--127.0.0.1-8702-1) [55f2ddfc] Running command: SetupNetworksCommand internal: false. Entities affected : ID: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 Type: VDS >2014-07-01 04:41:04,501 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp--127.0.0.1-8702-1) [55f2ddfc] START, SetupNetworksVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, force=false, checkConnectivity=true, conectivityTimeout=120, > networks=[], > bonds=[], > interfaces=[eth0 {id=44c228c6-2c2a-4f7f-899a-ab4ce9231100, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth0, macAddress=00:16:36:76:3e:ab, networkName=ovirtmgmt, bondName=null, bootProtocol=NONE, address=10.35.128.18, subnet=255.255.255.0, gateway=10.35.128.254, mtu=1500, bridged=true, speed=0, type=2, networkImplementationDetails={inSync=true, managed=true}}, > eth2 {id=0a752445-4430-4e6d-b51d-aa0139234261, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth2, macAddress=00:16:36:76:3E:A9, networkName=null, bondName=null, bootProtocol=NONE, address=, subnet=, gateway=null, mtu=1500, bridged=false, speed=1000, type=0, networkImplementationDetails=null}, > eth1 {id=34ede921-933f-4667-9931-9aa3988443c0, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth1, macAddress=00:16:36:76:3e:ac, networkName=null, bondName=null, bootProtocol=NONE, address=, subnet=, gateway=null, mtu=1500, bridged=false, speed=0, type=0, networkImplementationDetails=null}, > eth3 {id=4c415151-0484-4030-9d12-14bde9ba1e47, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth3, macAddress=00:16:36:76:3E:AA, networkName=null, bondName=null, bootProtocol=NONE, address=, subnet=, gateway=null, mtu=1500, bridged=false, speed=1000, type=0, networkImplementationDetails=null}], > removedNetworks=[net1, net5, net7], > removedBonds=[bond0]), log id: 4f6f7060 >2014-07-01 04:41:04,511 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp--127.0.0.1-8702-1) [55f2ddfc] FINISH, SetupNetworksVDSCommand, log id: 4f6f7060 >2014-07-01 04:41:04,511 WARN [org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil] (org.ovirt.thread.pool-8-thread-32) Executing a command: java.util.concurrent.FutureTask , but note that there are 1 tasks in the queue. >2014-07-01 04:41:08,988 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-2) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Interface bond0 on host orange-vdsd.qa.lab.tlv.redhat.com, changed state to down >2014-07-01 04:41:08,994 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-2) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Slave eth2 of bond bond0 on host orange-vdsd.qa.lab.tlv.redhat.com, changed state to down >2014-07-01 04:41:09,002 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-2) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Slave eth3 of bond bond0 on host orange-vdsd.qa.lab.tlv.redhat.com, changed state to down >2014-07-01 04:41:09,059 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-2) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Host orange-vdsd.qa.lab.tlv.redhat.com has network interface which exceeded the defined threshold [95%] (bond0: transmit rate[100%], receive rate [100%]) >2014-07-01 04:41:09,063 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-2) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Host orange-vdsd.qa.lab.tlv.redhat.com has network interface which exceeded the defined threshold [95%] (eth2: transmit rate[100%], receive rate [100%]) >2014-07-01 04:41:09,070 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-2) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Host orange-vdsd.qa.lab.tlv.redhat.com has network interface which exceeded the defined threshold [95%] (eth3: transmit rate[0%], receive rate [100%]) >2014-07-01 04:41:12,462 INFO [org.ovirt.engine.core.bll.network.host.CommitNetworkChangesCommand] (ajp--127.0.0.1-8702-12) [635d699c] Running command: CommitNetworkChangesCommand internal: false. Entities affected : ID: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 Type: VDS >2014-07-01 04:41:12,464 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetSafeNetworkConfigVDSCommand] (ajp--127.0.0.1-8702-12) [635d699c] START, SetSafeNetworkConfigVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6), log id: 3bf7740f >2014-07-01 04:41:12,506 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetSafeNetworkConfigVDSCommand] (ajp--127.0.0.1-8702-12) [635d699c] FINISH, SetSafeNetworkConfigVDSCommand, log id: 3bf7740f >2014-07-01 04:41:12,513 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-12) [635d699c] Correlation ID: 635d699c, Job ID: 45a82cd7-d1ac-4f74-8110-d118457f6907, Call Stack: null, Custom Event ID: -1, Message: Network changes were saved on host orange-vdsd.qa.lab.tlv.redhat.com >2014-07-01 04:43:15,285 INFO [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp--127.0.0.1-8702-4) [191cdc54] Lock Acquired to object EngineLock [exclusiveLocks= key: td_vm1 value: VM_NAME >, sharedLocks= ] >2014-07-01 04:43:15,352 INFO [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp--127.0.0.1-8702-4) [191cdc54] Running command: AddVmFromScratchCommand internal: false. Entities affected : ID: 00000001-0001-0001-0001-00000000034a Type: VdsGroups >2014-07-01 04:43:15,538 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) [191cdc54] Correlation ID: 191cdc54, Job ID: 71ad18a8-443e-4ff7-bd42-d5bbe4581dbb, Call Stack: null, Custom Event ID: -1, Message: VM td_vm1 was created by admin. >2014-07-01 04:43:15,543 INFO [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp--127.0.0.1-8702-4) [191cdc54] Lock freed to object EngineLock [exclusiveLocks= key: td_vm1 value: VM_NAME >, sharedLocks= ] >2014-07-01 04:43:17,044 INFO [org.ovirt.engine.core.bll.network.vm.AddVmInterfaceCommand] (ajp--127.0.0.1-8702-3) [20548e6c] Running command: AddVmInterfaceCommand internal: false. Entities affected : ID: 7e1fb1c7-700f-432d-b982-06b4cd3c436e Type: VM, ID: 0000000e-000e-000e-000e-0000000003c4 Type: VnicProfile >2014-07-01 04:43:17,095 INFO [org.ovirt.engine.core.bll.network.vm.ActivateDeactivateVmNicCommand] (ajp--127.0.0.1-8702-3) [7ba48ef8] Running command: ActivateDeactivateVmNicCommand internal: true. Entities affected : ID: 7e1fb1c7-700f-432d-b982-06b4cd3c436e Type: VM >2014-07-01 04:43:17,116 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [7ba48ef8] Correlation ID: 7ba48ef8, Call Stack: null, Custom Event ID: -1, Message: Network Interface nic1 (VirtIO) was plugged to VM td_vm1. (User: Non interactive user) >2014-07-01 04:43:17,125 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [7ba48ef8] Correlation ID: 20548e6c, Call Stack: null, Custom Event ID: -1, Message: Interface nic1 (VirtIO) was added to VM td_vm1. (User: admin) >2014-07-01 04:43:17,166 INFO [org.ovirt.engine.core.bll.network.vm.ReorderVmNicsCommand] (ajp--127.0.0.1-8702-9) [1ab106c9] Running command: ReorderVmNicsCommand internal: false. Entities affected : ID: 7e1fb1c7-700f-432d-b982-06b4cd3c436e Type: VM >2014-07-01 04:43:31,277 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (ajp--127.0.0.1-8702-5) [3d3da1c5] Lock Acquired to object EngineLock [exclusiveLocks= key: 7e1fb1c7-700f-432d-b982-06b4cd3c436e value: VM_DISK_BOOT >, sharedLocks= key: 7e1fb1c7-700f-432d-b982-06b4cd3c436e value: VM >] >2014-07-01 04:43:31,300 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (ajp--127.0.0.1-8702-5) [3d3da1c5] Running command: AddDiskCommand internal: false. Entities affected : ID: 7e1fb1c7-700f-432d-b982-06b4cd3c436e Type: VM, ID: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 Type: Storage >2014-07-01 04:43:31,437 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (ajp--127.0.0.1-8702-5) [e973ce7] Running command: AddImageFromScratchCommand internal: true. Entities affected : ID: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 Type: Storage >2014-07-01 04:43:31,482 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (ajp--127.0.0.1-8702-5) [e973ce7] Lock freed to object EngineLock [exclusiveLocks= key: 7e1fb1c7-700f-432d-b982-06b4cd3c436e value: VM_DISK_BOOT >, sharedLocks= key: 7e1fb1c7-700f-432d-b982-06b4cd3c436e value: VM >] >2014-07-01 04:43:31,483 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp--127.0.0.1-8702-5) [e973ce7] START, CreateImageVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false, storageDomainId = 86ecda08-9abd-4a3c-b13f-de0f3746ce82, imageGroupId = add5a4ed-3fce-44d2-ad57-921a747069d4, imageSizeInBytes = 21474836480, volumeFormat = RAW, newImageId = 2d8aa9d2-b54e-4997-95e7-e8e21322c3ce, newImageDescription = ), log id: 74fd853c >2014-07-01 04:43:31,488 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp--127.0.0.1-8702-5) [e973ce7] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID >2014-07-01 04:43:31,551 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (ajp--127.0.0.1-8702-5) [e973ce7] FINISH, CreateImageVDSCommand, return: 2d8aa9d2-b54e-4997-95e7-e8e21322c3ce, log id: 74fd853c >2014-07-01 04:43:31,697 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (ajp--127.0.0.1-8702-5) [e973ce7] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command b5188f5c-2335-4602-811d-c210fd671553 >2014-07-01 04:43:31,697 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp--127.0.0.1-8702-5) [e973ce7] CommandMultiAsyncTasks::AttachTask: Attaching task 0db09888-9226-4624-a4df-b591d3f764a2 to command b5188f5c-2335-4602-811d-c210fd671553. >2014-07-01 04:43:31,723 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (ajp--127.0.0.1-8702-5) [e973ce7] Adding task 0db09888-9226-4624-a4df-b591d3f764a2 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-01 04:43:31,753 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-5) [e973ce7] Correlation ID: 3d3da1c5, Job ID: 0d87e14e-4365-4dae-b90f-132ef124c0b7, Call Stack: null, Custom Event ID: -1, Message: Add-Disk operation of td_vm1_Disk1 was initiated on VM td_vm1 by admin. >2014-07-01 04:43:31,754 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (ajp--127.0.0.1-8702-5) [e973ce7] BaseAsyncTask::startPollingTask: Starting to poll task 0db09888-9226-4624-a4df-b591d3f764a2. >2014-07-01 04:43:41,741 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-31) Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now >2014-07-01 04:43:41,753 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-31) SPMAsyncTask::PollTask: Polling task 0db09888-9226-4624-a4df-b591d3f764a2 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-01 04:43:41,758 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-31) BaseAsyncTask::OnTaskEndSuccess: Task 0db09888-9226-4624-a4df-b591d3f764a2 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-01 04:43:41,758 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-31) CommandAsyncTask::EndActionIfNecessary: All tasks of command b5188f5c-2335-4602-811d-c210fd671553 has ended -> executing endAction >2014-07-01 04:43:41,763 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-31) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: b5188f5c-2335-4602-811d-c210fd671553): calling endAction . >2014-07-01 04:43:41,767 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-22) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction AddDisk, executionIndex: 0 >2014-07-01 04:43:41,782 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (org.ovirt.thread.pool-8-thread-22) [3d3da1c5] Ending command successfully: org.ovirt.engine.core.bll.AddDiskCommand >2014-07-01 04:43:41,819 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (org.ovirt.thread.pool-8-thread-22) [e973ce7] Ending command successfully: org.ovirt.engine.core.bll.AddImageFromScratchCommand >2014-07-01 04:43:41,838 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-22) [e973ce7] START, GetImageInfoVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false, storageDomainId = 86ecda08-9abd-4a3c-b13f-de0f3746ce82, imageGroupId = add5a4ed-3fce-44d2-ad57-921a747069d4, imageId = 2d8aa9d2-b54e-4997-95e7-e8e21322c3ce), log id: 1aba09d >2014-07-01 04:43:41,864 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-22) [e973ce7] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@95fd2d32, log id: 1aba09d >2014-07-01 04:43:41,879 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-22) Correlation ID: 3d3da1c5, Call Stack: null, Custom Event ID: -1, Message: The disk td_vm1_Disk1 was successfully added to VM td_vm1. >2014-07-01 04:43:41,880 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-22) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk completed, handling the result. >2014-07-01 04:43:41,880 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-22) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk succeeded, clearing tasks. >2014-07-01 04:43:41,887 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-22) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 0db09888-9226-4624-a4df-b591d3f764a2 >2014-07-01 04:43:41,888 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-22) START, SPMClearTaskVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false, taskId = 0db09888-9226-4624-a4df-b591d3f764a2), log id: bec1ef1 >2014-07-01 04:43:41,889 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-22) START, HSMClearTaskVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, taskId=0db09888-9226-4624-a4df-b591d3f764a2), log id: 3ae36654 >2014-07-01 04:43:41,906 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-22) FINISH, HSMClearTaskVDSCommand, log id: 3ae36654 >2014-07-01 04:43:41,906 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-22) FINISH, SPMClearTaskVDSCommand, log id: bec1ef1 >2014-07-01 04:43:41,927 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-22) BaseAsyncTask::RemoveTaskFromDB: Removed task 0db09888-9226-4624-a4df-b591d3f764a2 from DataBase >2014-07-01 04:43:41,927 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-22) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity b5188f5c-2335-4602-811d-c210fd671553 >2014-07-01 04:44:51,460 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-77) Setting new tasks map. The map contains now 0 tasks >2014-07-01 04:44:51,461 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-77) Cleared all tasks of pool 00000002-0002-0002-0002-000000000251. >2014-07-01 05:09:51,614 INFO [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-27) Attempting to update VMs/Templates Ovf. >2014-07-01 05:09:51,843 INFO [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (DefaultQuartzScheduler_Worker-27) [2c6e68d2] Lock Acquired to object EngineLock [exclusiveLocks= key: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 value: STORAGE >, sharedLocks= key: 00000002-0002-0002-0002-000000000251 value: OVF_UPDATE >] >2014-07-01 05:09:51,845 INFO [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (DefaultQuartzScheduler_Worker-27) [2c6e68d2] Running command: ProcessOvfUpdateForStorageDomainCommand internal: true. Entities affected : ID: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 Type: Storage >2014-07-01 05:09:51,858 INFO [org.ovirt.engine.core.bll.CreateOvfStoresForStorageDomainCommand] (DefaultQuartzScheduler_Worker-27) [357e07ff] Running command: CreateOvfStoresForStorageDomainCommand internal: true. >2014-07-01 05:09:51,859 INFO [org.ovirt.engine.core.bll.CreateOvfVolumeForStorageDomainCommand] (DefaultQuartzScheduler_Worker-27) [2ab7f7fc] Running command: CreateOvfVolumeForStorageDomainCommand internal: true. Entities affected : ID: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 Type: Storage >2014-07-01 05:09:51,875 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (DefaultQuartzScheduler_Worker-27) [229247ea] Running command: AddDiskCommand internal: true. Entities affected : ID: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 Type: Storage >2014-07-01 05:09:51,893 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (DefaultQuartzScheduler_Worker-27) [2892a3a3] Running command: AddImageFromScratchCommand internal: true. Entities affected : ID: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 Type: Storage >2014-07-01 05:09:51,906 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (DefaultQuartzScheduler_Worker-27) [2892a3a3] START, CreateImageVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false, storageDomainId = 86ecda08-9abd-4a3c-b13f-de0f3746ce82, imageGroupId = 8a8b4272-a751-4b50-9e2f-316c00cc97ef, imageSizeInBytes = 134217728, volumeFormat = RAW, newImageId = 22b25899-addd-478a-872b-19ecaa3c70f3, newImageDescription = OVF_STORE), log id: 3770c676 >2014-07-01 05:09:51,907 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (DefaultQuartzScheduler_Worker-27) [2892a3a3] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID >2014-07-01 05:09:51,977 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (DefaultQuartzScheduler_Worker-27) [2892a3a3] FINISH, CreateImageVDSCommand, return: 22b25899-addd-478a-872b-19ecaa3c70f3, log id: 3770c676 >2014-07-01 05:09:51,989 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-27) [2892a3a3] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 08ce68a9-e7e3-4966-a0b9-c80fc2ea25b4 >2014-07-01 05:09:51,990 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-27) [2892a3a3] CommandMultiAsyncTasks::AttachTask: Attaching task 58193f13-d761-4815-bd55-0b6330c62db0 to command 08ce68a9-e7e3-4966-a0b9-c80fc2ea25b4. >2014-07-01 05:09:52,005 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-27) [2892a3a3] Adding task 58193f13-d761-4815-bd55-0b6330c62db0 (Parent Command CreateOvfStoresForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-01 05:09:52,009 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-27) [2892a3a3] Correlation ID: 229247ea, Call Stack: null, Custom Event ID: -1, Message: Add-Disk operation of 'OVF_STORE' was initiated by Non interactive user. >2014-07-01 05:09:52,019 INFO [org.ovirt.engine.core.bll.CreateOvfVolumeForStorageDomainCommand] (DefaultQuartzScheduler_Worker-27) [447d39f6] Running command: CreateOvfVolumeForStorageDomainCommand internal: true. Entities affected : ID: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 Type: Storage >2014-07-01 05:09:52,029 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (DefaultQuartzScheduler_Worker-27) [7968689a] Running command: AddDiskCommand internal: true. Entities affected : ID: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 Type: Storage >2014-07-01 05:09:52,041 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (DefaultQuartzScheduler_Worker-27) [4a2e75a0] Running command: AddImageFromScratchCommand internal: true. Entities affected : ID: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 Type: Storage >2014-07-01 05:09:52,046 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (DefaultQuartzScheduler_Worker-27) [4a2e75a0] START, CreateImageVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false, storageDomainId = 86ecda08-9abd-4a3c-b13f-de0f3746ce82, imageGroupId = a1624e00-65dc-434f-919c-a61d8b18d8ff, imageSizeInBytes = 134217728, volumeFormat = RAW, newImageId = 293e1f0f-c2b8-4500-ae6f-e0d474175c43, newImageDescription = OVF_STORE), log id: 4ba75d03 >2014-07-01 05:09:52,048 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (DefaultQuartzScheduler_Worker-27) [4a2e75a0] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID >2014-07-01 05:09:52,528 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (DefaultQuartzScheduler_Worker-27) [4a2e75a0] FINISH, CreateImageVDSCommand, return: 293e1f0f-c2b8-4500-ae6f-e0d474175c43, log id: 4ba75d03 >2014-07-01 05:09:52,533 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-27) [4a2e75a0] CommandMultiAsyncTasks::AttachTask: Attaching task 00daee7b-dd92-4852-8dac-4fb2d10051cf to command 08ce68a9-e7e3-4966-a0b9-c80fc2ea25b4. >2014-07-01 05:09:52,543 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-27) [4a2e75a0] Adding task 00daee7b-dd92-4852-8dac-4fb2d10051cf (Parent Command CreateOvfStoresForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-01 05:09:52,546 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-27) [4a2e75a0] Correlation ID: 7968689a, Call Stack: null, Custom Event ID: -1, Message: Add-Disk operation of 'OVF_STORE' was initiated by Non interactive user. >2014-07-01 05:09:52,548 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-27) [4a2e75a0] BaseAsyncTask::startPollingTask: Starting to poll task 58193f13-d761-4815-bd55-0b6330c62db0. >2014-07-01 05:09:52,549 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-27) [4a2e75a0] BaseAsyncTask::startPollingTask: Starting to poll task 00daee7b-dd92-4852-8dac-4fb2d10051cf. >2014-07-01 05:09:52,549 INFO [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (DefaultQuartzScheduler_Worker-27) [4a2e75a0] Lock freed to object EngineLock [exclusiveLocks= key: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 value: STORAGE >, sharedLocks= key: 00000002-0002-0002-0002-000000000251 value: OVF_UPDATE >] >2014-07-01 05:10:01,791 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-53) Polling and updating Async Tasks: 2 tasks, 2 tasks to poll now >2014-07-01 05:10:01,802 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-53) SPMAsyncTask::PollTask: Polling task 58193f13-d761-4815-bd55-0b6330c62db0 (Parent Command CreateOvfStoresForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-01 05:10:01,803 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-53) BaseAsyncTask::OnTaskEndSuccess: Task 58193f13-d761-4815-bd55-0b6330c62db0 (Parent Command CreateOvfStoresForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-01 05:10:01,804 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-53) Task with DB Task ID c14ed14a-d1b4-4b0c-8fc5-0d170aebdfb3 and VDSM Task ID 00daee7b-dd92-4852-8dac-4fb2d10051cf is in state Polling. End action for command 08ce68a9-e7e3-4966-a0b9-c80fc2ea25b4 will proceed when all the entitys tasks are completed. >2014-07-01 05:10:01,805 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-53) SPMAsyncTask::PollTask: Polling task 00daee7b-dd92-4852-8dac-4fb2d10051cf (Parent Command CreateOvfStoresForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-01 05:10:01,805 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-53) BaseAsyncTask::OnTaskEndSuccess: Task 00daee7b-dd92-4852-8dac-4fb2d10051cf (Parent Command CreateOvfStoresForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-01 05:10:01,806 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-53) CommandAsyncTask::EndActionIfNecessary: All tasks of command 08ce68a9-e7e3-4966-a0b9-c80fc2ea25b4 has ended -> executing endAction >2014-07-01 05:10:01,806 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-53) CommandAsyncTask::endAction: Ending action for 2 tasks (command ID: 08ce68a9-e7e3-4966-a0b9-c80fc2ea25b4): calling endAction . >2014-07-01 05:10:01,810 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-49) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction CreateOvfStoresForStorageDomain, executionIndex: 0 >2014-07-01 05:10:01,811 INFO [org.ovirt.engine.core.bll.CreateOvfStoresForStorageDomainCommand] (org.ovirt.thread.pool-8-thread-49) [357e07ff] Ending command successfully: org.ovirt.engine.core.bll.CreateOvfStoresForStorageDomainCommand >2014-07-01 05:10:01,817 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (org.ovirt.thread.pool-8-thread-49) [2892a3a3] Ending command successfully: org.ovirt.engine.core.bll.AddImageFromScratchCommand >2014-07-01 05:10:01,825 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-49) [2892a3a3] START, GetImageInfoVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false, storageDomainId = 86ecda08-9abd-4a3c-b13f-de0f3746ce82, imageGroupId = 8a8b4272-a751-4b50-9e2f-316c00cc97ef, imageId = 22b25899-addd-478a-872b-19ecaa3c70f3), log id: 551aafe >2014-07-01 05:10:01,849 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-49) [2892a3a3] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@99a7025e, log id: 551aafe >2014-07-01 05:10:01,863 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (org.ovirt.thread.pool-8-thread-49) [4a2e75a0] Ending command successfully: org.ovirt.engine.core.bll.AddImageFromScratchCommand >2014-07-01 05:10:01,866 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-49) [4a2e75a0] START, GetImageInfoVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false, storageDomainId = 86ecda08-9abd-4a3c-b13f-de0f3746ce82, imageGroupId = a1624e00-65dc-434f-919c-a61d8b18d8ff, imageId = 293e1f0f-c2b8-4500-ae6f-e0d474175c43), log id: 11333305 >2014-07-01 05:10:01,892 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-49) [4a2e75a0] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@1d2668c6, log id: 11333305 >2014-07-01 05:10:01,931 INFO [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (org.ovirt.thread.pool-8-thread-49) [357e07ff] Lock Acquired to object EngineLock [exclusiveLocks= key: a1624e00-65dc-434f-919c-a61d8b18d8ff value: DISK >key: 8a8b4272-a751-4b50-9e2f-316c00cc97ef value: DISK >key: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 value: STORAGE >, sharedLocks= key: 00000002-0002-0002-0002-000000000251 value: OVF_UPDATE >] >2014-07-01 05:10:01,934 INFO [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (org.ovirt.thread.pool-8-thread-49) [357e07ff] Running command: ProcessOvfUpdateForStorageDomainCommand internal: true. Entities affected : ID: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 Type: Storage >2014-07-01 05:10:01,959 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (org.ovirt.thread.pool-8-thread-49) [357e07ff] START, SetVolumeDescriptionVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false, storageDomainId = 86ecda08-9abd-4a3c-b13f-de0f3746ce82, imageGroupId = 8a8b4272-a751-4b50-9e2f-316c00cc97ef, imageId = 22b25899-addd-478a-872b-19ecaa3c70f3), log id: 2b3adfb5 >2014-07-01 05:10:01,967 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (org.ovirt.thread.pool-8-thread-49) [357e07ff] -- executeIrsBrokerCommand: calling 'setVolumeDescription' >2014-07-01 05:10:01,967 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (org.ovirt.thread.pool-8-thread-49) [357e07ff] -- setVolumeDescription parameters: > spUUID=00000002-0002-0002-0002-000000000251 > sdUUID=86ecda08-9abd-4a3c-b13f-de0f3746ce82 > imageGroupGUID=8a8b4272-a751-4b50-9e2f-316c00cc97ef > volUUID=22b25899-addd-478a-872b-19ecaa3c70f3 > description={"Updated":false,"Disk Description":"OVF_STORE","Storage Domains":[{"uuid":"86ecda08-9abd-4a3c-b13f-de0f3746ce82"}],"Last Updated":null} > >2014-07-01 05:10:02,014 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (org.ovirt.thread.pool-8-thread-49) [357e07ff] FINISH, SetVolumeDescriptionVDSCommand, log id: 2b3adfb5 >2014-07-01 05:10:02,042 INFO [org.ovirt.engine.core.bll.UploadStreamCommand] (org.ovirt.thread.pool-8-thread-49) [4054c9bf] Running command: UploadStreamCommand internal: true. Entities affected : ID: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 Type: Storage >2014-07-01 05:10:02,051 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (org.ovirt.thread.pool-8-thread-49) [4054c9bf] START, UploadStreamVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6), log id: dc14ebb >2014-07-01 05:10:02,051 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (org.ovirt.thread.pool-8-thread-49) [4054c9bf] -- executeVdsBrokerCommand: >2014-07-01 05:10:02,052 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (org.ovirt.thread.pool-8-thread-49) [4054c9bf] -- parameters: > spUUID=00000002-0002-0002-0002-000000000251 > sdUUID=86ecda08-9abd-4a3c-b13f-de0f3746ce82 > imageGUID=8a8b4272-a751-4b50-9e2f-316c00cc97ef > volUUID=22b25899-addd-478a-872b-19ecaa3c70f3 > size=10,240 > >2014-07-01 05:10:02,196 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (org.ovirt.thread.pool-8-thread-49) [4054c9bf] FINISH, UploadStreamVDSCommand, log id: dc14ebb >2014-07-01 05:10:02,216 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (org.ovirt.thread.pool-8-thread-49) [4054c9bf] CommandMultiAsyncTasks::AttachTask: Attaching task 919f4eba-973d-43f2-9950-b429256790e8 to command 08ce68a9-e7e3-4966-a0b9-c80fc2ea25b4. >2014-07-01 05:10:02,233 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-8-thread-49) [4054c9bf] Adding task 919f4eba-973d-43f2-9950-b429256790e8 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-01 05:10:02,234 INFO [org.ovirt.engine.core.bll.UploadStreamCommand] (org.ovirt.thread.pool-8-thread-49) [4054c9bf] Lock freed to object EngineLock [exclusiveLocks= key: a1624e00-65dc-434f-919c-a61d8b18d8ff value: DISK >key: 8a8b4272-a751-4b50-9e2f-316c00cc97ef value: DISK >key: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 value: STORAGE >, sharedLocks= key: 00000002-0002-0002-0002-000000000251 value: OVF_UPDATE >] >2014-07-01 05:10:02,243 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (org.ovirt.thread.pool-8-thread-49) [4054c9bf] START, SetVolumeDescriptionVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false, storageDomainId = 86ecda08-9abd-4a3c-b13f-de0f3746ce82, imageGroupId = 8a8b4272-a751-4b50-9e2f-316c00cc97ef, imageId = 22b25899-addd-478a-872b-19ecaa3c70f3), log id: 4d98f244 >2014-07-01 05:10:02,248 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (org.ovirt.thread.pool-8-thread-49) [4054c9bf] -- executeIrsBrokerCommand: calling 'setVolumeDescription' >2014-07-01 05:10:02,248 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (org.ovirt.thread.pool-8-thread-49) [4054c9bf] -- setVolumeDescription parameters: > spUUID=00000002-0002-0002-0002-000000000251 > sdUUID=86ecda08-9abd-4a3c-b13f-de0f3746ce82 > imageGroupGUID=8a8b4272-a751-4b50-9e2f-316c00cc97ef > volUUID=22b25899-addd-478a-872b-19ecaa3c70f3 > description={"Updated":true,"Disk Description":"OVF_STORE","Storage Domains":[{"uuid":"86ecda08-9abd-4a3c-b13f-de0f3746ce82"}],"Last Updated":"Tue Jul 01 05:10:01 GMT-05:00 2014","Size":10240} > >2014-07-01 05:10:02,293 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (org.ovirt.thread.pool-8-thread-49) [4054c9bf] FINISH, SetVolumeDescriptionVDSCommand, log id: 4d98f244 >2014-07-01 05:10:02,299 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (org.ovirt.thread.pool-8-thread-49) [4054c9bf] START, SetVolumeDescriptionVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false, storageDomainId = 86ecda08-9abd-4a3c-b13f-de0f3746ce82, imageGroupId = a1624e00-65dc-434f-919c-a61d8b18d8ff, imageId = 293e1f0f-c2b8-4500-ae6f-e0d474175c43), log id: 3c1dfe95 >2014-07-01 05:10:02,299 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (org.ovirt.thread.pool-8-thread-49) [4054c9bf] -- executeIrsBrokerCommand: calling 'setVolumeDescription' >2014-07-01 05:10:02,300 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (org.ovirt.thread.pool-8-thread-49) [4054c9bf] -- setVolumeDescription parameters: > spUUID=00000002-0002-0002-0002-000000000251 > sdUUID=86ecda08-9abd-4a3c-b13f-de0f3746ce82 > imageGroupGUID=a1624e00-65dc-434f-919c-a61d8b18d8ff > volUUID=293e1f0f-c2b8-4500-ae6f-e0d474175c43 > description={"Updated":false,"Disk Description":"OVF_STORE","Storage Domains":[{"uuid":"86ecda08-9abd-4a3c-b13f-de0f3746ce82"}],"Last Updated":null} > >2014-07-01 05:10:02,379 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (org.ovirt.thread.pool-8-thread-49) [4054c9bf] FINISH, SetVolumeDescriptionVDSCommand, log id: 3c1dfe95 >2014-07-01 05:10:02,389 INFO [org.ovirt.engine.core.bll.UploadStreamCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] Running command: UploadStreamCommand internal: true. Entities affected : ID: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 Type: Storage >2014-07-01 05:10:02,390 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] START, UploadStreamVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6), log id: 1c464db3 >2014-07-01 05:10:02,391 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] -- executeVdsBrokerCommand: >2014-07-01 05:10:02,391 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] -- parameters: > spUUID=00000002-0002-0002-0002-000000000251 > sdUUID=86ecda08-9abd-4a3c-b13f-de0f3746ce82 > imageGUID=a1624e00-65dc-434f-919c-a61d8b18d8ff > volUUID=293e1f0f-c2b8-4500-ae6f-e0d474175c43 > size=10,240 > >2014-07-01 05:10:02,675 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] FINISH, UploadStreamVDSCommand, log id: 1c464db3 >2014-07-01 05:10:02,676 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] CommandMultiAsyncTasks::AttachTask: Attaching task 70bf53d2-fd5f-4ede-b99f-a3b08bd46e2d to command 08ce68a9-e7e3-4966-a0b9-c80fc2ea25b4. >2014-07-01 05:10:02,680 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] Adding task 70bf53d2-fd5f-4ede-b99f-a3b08bd46e2d (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-01 05:10:02,681 WARN [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] Trying to release a shared lock for key: 00000002-0002-0002-0002-000000000251OVF_UPDATE , but lock does not exist >2014-07-01 05:10:02,681 WARN [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] Trying to release exclusive lock which does not exist, lock key: a1624e00-65dc-434f-919c-a61d8b18d8ffDISK >2014-07-01 05:10:02,682 WARN [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] Trying to release exclusive lock which does not exist, lock key: 8a8b4272-a751-4b50-9e2f-316c00cc97efDISK >2014-07-01 05:10:02,682 WARN [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] Trying to release exclusive lock which does not exist, lock key: 86ecda08-9abd-4a3c-b13f-de0f3746ce82STORAGE >2014-07-01 05:10:02,683 INFO [org.ovirt.engine.core.bll.UploadStreamCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] Lock freed to object EngineLock [exclusiveLocks= key: a1624e00-65dc-434f-919c-a61d8b18d8ff value: DISK >key: 8a8b4272-a751-4b50-9e2f-316c00cc97ef value: DISK >key: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 value: STORAGE >, sharedLocks= key: 00000002-0002-0002-0002-000000000251 value: OVF_UPDATE >] >2014-07-01 05:10:02,684 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] START, SetVolumeDescriptionVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false, storageDomainId = 86ecda08-9abd-4a3c-b13f-de0f3746ce82, imageGroupId = a1624e00-65dc-434f-919c-a61d8b18d8ff, imageId = 293e1f0f-c2b8-4500-ae6f-e0d474175c43), log id: 5bc2c5c4 >2014-07-01 05:10:02,685 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] -- executeIrsBrokerCommand: calling 'setVolumeDescription' >2014-07-01 05:10:02,685 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] -- setVolumeDescription parameters: > spUUID=00000002-0002-0002-0002-000000000251 > sdUUID=86ecda08-9abd-4a3c-b13f-de0f3746ce82 > imageGroupGUID=a1624e00-65dc-434f-919c-a61d8b18d8ff > volUUID=293e1f0f-c2b8-4500-ae6f-e0d474175c43 > description={"Updated":true,"Disk Description":"OVF_STORE","Storage Domains":[{"uuid":"86ecda08-9abd-4a3c-b13f-de0f3746ce82"}],"Last Updated":"Tue Jul 01 05:10:01 GMT-05:00 2014","Size":10240} > >2014-07-01 05:10:02,736 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] FINISH, SetVolumeDescriptionVDSCommand, log id: 5bc2c5c4 >2014-07-01 05:10:02,738 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] BaseAsyncTask::startPollingTask: Starting to poll task 919f4eba-973d-43f2-9950-b429256790e8. >2014-07-01 05:10:02,738 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] BaseAsyncTask::startPollingTask: Starting to poll task 70bf53d2-fd5f-4ede-b99f-a3b08bd46e2d. >2014-07-01 05:10:02,739 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type CreateOvfStoresForStorageDomain completed, handling the result. >2014-07-01 05:10:02,740 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type CreateOvfStoresForStorageDomain succeeded, clearing tasks. >2014-07-01 05:10:02,742 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] SPMAsyncTask::ClearAsyncTask: Attempting to clear task 58193f13-d761-4815-bd55-0b6330c62db0 >2014-07-01 05:10:02,742 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] START, SPMClearTaskVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false, taskId = 58193f13-d761-4815-bd55-0b6330c62db0), log id: 14e3ade9 >2014-07-01 05:10:02,744 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] START, HSMClearTaskVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, taskId=58193f13-d761-4815-bd55-0b6330c62db0), log id: 3342ee5b >2014-07-01 05:10:02,808 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] FINISH, HSMClearTaskVDSCommand, log id: 3342ee5b >2014-07-01 05:10:02,808 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] FINISH, SPMClearTaskVDSCommand, log id: 14e3ade9 >2014-07-01 05:10:02,817 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] BaseAsyncTask::RemoveTaskFromDB: Removed task 58193f13-d761-4815-bd55-0b6330c62db0 from DataBase >2014-07-01 05:10:02,818 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] SPMAsyncTask::ClearAsyncTask: Attempting to clear task 00daee7b-dd92-4852-8dac-4fb2d10051cf >2014-07-01 05:10:02,818 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] START, SPMClearTaskVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false, taskId = 00daee7b-dd92-4852-8dac-4fb2d10051cf), log id: 7f27f550 >2014-07-01 05:10:02,824 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] START, HSMClearTaskVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, taskId=00daee7b-dd92-4852-8dac-4fb2d10051cf), log id: 185a9868 >2014-07-01 05:10:02,880 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] FINISH, HSMClearTaskVDSCommand, log id: 185a9868 >2014-07-01 05:10:02,881 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] FINISH, SPMClearTaskVDSCommand, log id: 7f27f550 >2014-07-01 05:10:02,884 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] BaseAsyncTask::RemoveTaskFromDB: Removed task 00daee7b-dd92-4852-8dac-4fb2d10051cf from DataBase >2014-07-01 05:10:02,885 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (org.ovirt.thread.pool-8-thread-49) [25ce8e3d] [within thread]: Some of the tasks related to command id 08ce68a9-e7e3-4966-a0b9-c80fc2ea25b4 were not cleared yet (Task id 919f4eba-973d-43f2-9950-b429256790e8 is in state Polling). >2014-07-01 05:10:11,814 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-74) Polling and updating Async Tasks: 4 tasks, 2 tasks to poll now >2014-07-01 05:10:11,825 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-74) SPMAsyncTask::PollTask: Polling task 919f4eba-973d-43f2-9950-b429256790e8 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-01 05:10:11,825 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-74) BaseAsyncTask::OnTaskEndSuccess: Task 919f4eba-973d-43f2-9950-b429256790e8 (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-01 05:10:11,826 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-74) Task with DB Task ID a957a68f-71ed-41a8-88b8-82efe557ea83 and VDSM Task ID 70bf53d2-fd5f-4ede-b99f-a3b08bd46e2d is in state Polling. End action for command 08ce68a9-e7e3-4966-a0b9-c80fc2ea25b4 will proceed when all the entitys tasks are completed. >2014-07-01 05:10:11,834 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-74) SPMAsyncTask::PollTask: Polling task 70bf53d2-fd5f-4ede-b99f-a3b08bd46e2d (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-01 05:10:11,834 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-74) BaseAsyncTask::OnTaskEndSuccess: Task 70bf53d2-fd5f-4ede-b99f-a3b08bd46e2d (Parent Command ProcessOvfUpdateForStorageDomain, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-01 05:10:11,839 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-74) CommandAsyncTask::EndActionIfNecessary: All tasks of command 08ce68a9-e7e3-4966-a0b9-c80fc2ea25b4 has ended -> executing endAction >2014-07-01 05:10:11,840 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-74) CommandAsyncTask::endAction: Ending action for 2 tasks (command ID: 08ce68a9-e7e3-4966-a0b9-c80fc2ea25b4): calling endAction . >2014-07-01 05:10:11,840 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-17) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction ProcessOvfUpdateForStorageDomain, executionIndex: 0 >2014-07-01 05:10:11,893 INFO [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (org.ovirt.thread.pool-8-thread-17) [357e07ff] Ending command successfully: org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand >2014-07-01 05:10:11,894 WARN [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-8-thread-17) [357e07ff] Trying to release a shared lock for key: 00000002-0002-0002-0002-000000000251OVF_UPDATE , but lock does not exist >2014-07-01 05:10:11,895 WARN [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-8-thread-17) [357e07ff] Trying to release exclusive lock which does not exist, lock key: a1624e00-65dc-434f-919c-a61d8b18d8ffDISK >2014-07-01 05:10:11,895 WARN [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-8-thread-17) [357e07ff] Trying to release exclusive lock which does not exist, lock key: 8a8b4272-a751-4b50-9e2f-316c00cc97efDISK >2014-07-01 05:10:11,896 WARN [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-8-thread-17) [357e07ff] Trying to release exclusive lock which does not exist, lock key: 86ecda08-9abd-4a3c-b13f-de0f3746ce82STORAGE >2014-07-01 05:10:11,896 INFO [org.ovirt.engine.core.bll.ProcessOvfUpdateForStorageDomainCommand] (org.ovirt.thread.pool-8-thread-17) [357e07ff] Lock freed to object EngineLock [exclusiveLocks= key: a1624e00-65dc-434f-919c-a61d8b18d8ff value: DISK >key: 8a8b4272-a751-4b50-9e2f-316c00cc97ef value: DISK >key: 86ecda08-9abd-4a3c-b13f-de0f3746ce82 value: STORAGE >, sharedLocks= key: 00000002-0002-0002-0002-000000000251 value: OVF_UPDATE >] >2014-07-01 05:10:11,901 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-17) [357e07ff] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ProcessOvfUpdateForStorageDomain completed, handling the result. >2014-07-01 05:10:11,901 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-17) [357e07ff] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ProcessOvfUpdateForStorageDomain succeeded, clearing tasks. >2014-07-01 05:10:11,902 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-17) [357e07ff] SPMAsyncTask::ClearAsyncTask: Attempting to clear task 919f4eba-973d-43f2-9950-b429256790e8 >2014-07-01 05:10:11,906 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-17) [357e07ff] START, SPMClearTaskVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false, taskId = 919f4eba-973d-43f2-9950-b429256790e8), log id: 5681fcde >2014-07-01 05:10:11,909 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-17) [357e07ff] START, HSMClearTaskVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, taskId=919f4eba-973d-43f2-9950-b429256790e8), log id: 70007f30 >2014-07-01 05:10:11,923 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-17) [357e07ff] FINISH, HSMClearTaskVDSCommand, log id: 70007f30 >2014-07-01 05:10:11,924 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-17) [357e07ff] FINISH, SPMClearTaskVDSCommand, log id: 5681fcde >2014-07-01 05:10:11,929 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-17) [357e07ff] BaseAsyncTask::RemoveTaskFromDB: Removed task 919f4eba-973d-43f2-9950-b429256790e8 from DataBase >2014-07-01 05:10:11,929 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-17) [357e07ff] SPMAsyncTask::ClearAsyncTask: Attempting to clear task 70bf53d2-fd5f-4ede-b99f-a3b08bd46e2d >2014-07-01 05:10:11,930 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-17) [357e07ff] START, SPMClearTaskVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false, taskId = 70bf53d2-fd5f-4ede-b99f-a3b08bd46e2d), log id: 6ab45752 >2014-07-01 05:10:11,933 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-17) [357e07ff] START, HSMClearTaskVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, taskId=70bf53d2-fd5f-4ede-b99f-a3b08bd46e2d), log id: 65d0ff38 >2014-07-01 05:10:11,989 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-17) [357e07ff] FINISH, HSMClearTaskVDSCommand, log id: 65d0ff38 >2014-07-01 05:10:11,989 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-17) [357e07ff] FINISH, SPMClearTaskVDSCommand, log id: 6ab45752 >2014-07-01 05:10:11,994 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-17) [357e07ff] BaseAsyncTask::RemoveTaskFromDB: Removed task 70bf53d2-fd5f-4ede-b99f-a3b08bd46e2d from DataBase >2014-07-01 05:10:11,995 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-17) [357e07ff] CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 08ce68a9-e7e3-4966-a0b9-c80fc2ea25b4 >2014-07-01 05:11:21,473 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-22) Setting new tasks map. The map contains now 0 tasks >2014-07-01 05:11:21,474 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-22) Cleared all tasks of pool 00000002-0002-0002-0002-000000000251. >2014-07-01 05:49:03,188 INFO [org.ovirt.engine.core.bll.LoginAdminUserCommand] (ajp--127.0.0.1-8702-3) Running command: LoginAdminUserCommand internal: false. >2014-07-01 05:49:03,198 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin logged in. >2014-07-01 05:49:04,464 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-7) Running command: LoginUserCommand internal: false. >2014-07-01 05:54:28,106 INFO [org.ovirt.engine.core.bll.network.dc.AddNetworkCommand] (ajp--127.0.0.1-8702-9) [615591cd] Lock Acquired to object EngineLock [exclusiveLocks= key: vm_net value: NETWORK >, sharedLocks= ] >2014-07-01 05:54:28,109 INFO [org.ovirt.engine.core.bll.network.dc.AddNetworkCommand] (ajp--127.0.0.1-8702-9) [615591cd] Running command: AddNetworkCommand internal: false. Entities affected : ID: 00000002-0002-0002-0002-000000000251 Type: StoragePool >2014-07-01 05:54:28,129 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-9) [615591cd] Correlation ID: 615591cd, Call Stack: null, Custom Event ID: -1, Message: Network vm_net was added to Data Center: Default >2014-07-01 05:54:28,129 INFO [org.ovirt.engine.core.bll.network.dc.AddNetworkCommand] (ajp--127.0.0.1-8702-9) [615591cd] Lock freed to object EngineLock [exclusiveLocks= key: vm_net value: NETWORK >, sharedLocks= ] >2014-07-01 05:54:28,235 INFO [org.ovirt.engine.core.bll.network.vm.AddVnicProfileCommand] (ajp--127.0.0.1-8702-11) [307442e2] Running command: AddVnicProfileCommand internal: false. Entities affected : ID: 66367911-a0d0-4187-b46d-13c3bc065348 Type: Network >2014-07-01 05:54:28,264 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-11) [307442e2] Correlation ID: 307442e2, Call Stack: null, Custom Event ID: -1, Message: VM network interface profile vm_net was added to network vm_net in Data Center: Default. (User: admin) >2014-07-01 05:54:28,267 INFO [org.ovirt.engine.core.bll.network.cluster.AttachNetworkToVdsGroupCommand] (org.ovirt.thread.pool-8-thread-34) [b12d13b] Running command: AttachNetworkToVdsGroupCommand internal: false. Entities affected : ID: 66367911-a0d0-4187-b46d-13c3bc065348 Type: Network >2014-07-01 05:54:28,340 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-34) Correlation ID: b12d13b, Call Stack: null, Custom Event ID: -1, Message: Network vm_net attached to Cluster Default >2014-07-01 06:09:52,550 INFO [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-31) Attempting to update VMs/Templates Ovf. >2014-07-01 06:16:27,945 INFO [org.ovirt.engine.core.bll.network.dc.UpdateNetworkCommand] (ajp--127.0.0.1-8702-9) [30a0f63f] Running command: UpdateNetworkCommand internal: false. Entities affected : ID: 66367911-a0d0-4187-b46d-13c3bc065348 Type: Network >2014-07-01 06:16:27,974 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-9) Correlation ID: 30a0f63f, Call Stack: null, Custom Event ID: -1, Message: Network vm_net was updated on Data Center: Default >2014-07-01 06:19:17,627 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStatsVDSCommand] (DefaultQuartzScheduler_Worker-30) Command GetStatsVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vds=Host[orange-vdsd.qa.lab.tlv.redhat.com,8749c6c4-ffd0-4f2d-a877-09a832e7a8f6]) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-07-01 06:19:17,630 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-30) Host orange-vdsd.qa.lab.tlv.redhat.com is not responding. It will stay in Connecting state for a grace period of $80 seconds and after that an attempt to fence the host will be issued. >2014-07-01 06:19:17,630 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-30) vds::refreshVdsStats Failed getVdsStats, vds = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 : orange-vdsd.qa.lab.tlv.redhat.com, error = VDSNetworkException: java.net.ConnectException: Connection refused >2014-07-01 06:19:17,631 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-30) Failure to refresh Vds runtime info: org.ovirt.engine.core.vdsbroker.vdsbroker.VDSNetworkException: java.net.ConnectException: Connection refused > at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.createNetworkException(VdsBrokerCommand.java:126) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.executeVDSCommand(VdsBrokerCommand.java:101) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.VDSCommandBase.executeCommand(VDSCommandBase.java:56) [vdsbroker.jar:] > at org.ovirt.engine.core.dal.VdcCommandBase.execute(VdcCommandBase.java:31) [dal.jar:] > at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.refreshVdsStats(VdsUpdateRunTimeInfo.java:669) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.refreshVdsRunTimeInfo(VdsUpdateRunTimeInfo.java:623) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.refresh(VdsUpdateRunTimeInfo.java:485) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.VdsManager.onTimer(VdsManager.java:235) [vdsbroker.jar:] > at sun.reflect.GeneratedMethodAccessor59.invoke(Unknown Source) [:1.7.0_55] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_55] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_55] > at org.ovirt.engine.core.utils.timer.JobWrapper.execute(JobWrapper.java:60) [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.net.ConnectException: Connection refused > at java.net.PlainSocketImpl.socketConnect(Native Method) [rt.jar:1.7.0_55] > at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339) [rt.jar:1.7.0_55] > at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200) [rt.jar:1.7.0_55] > at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182) [rt.jar:1.7.0_55] > at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) [rt.jar:1.7.0_55] > at java.net.Socket.connect(Socket.java:579) [rt.jar:1.7.0_55] > at sun.security.ssl.SSLSocketImpl.connect(SSLSocketImpl.java:618) [jsse.jar:1.7.0_55] > at org.ovirt.engine.core.utils.ssl.AuthSSLProtocolSocketFactory.createSocket(AuthSSLProtocolSocketFactory.java:288) [utils.jar:] > at org.apache.commons.httpclient.HttpConnection.open(HttpConnection.java:707) [commons-httpclient.jar:] > at org.apache.commons.httpclient.MultiThreadedHttpConnectionManager$HttpConnectionAdapter.open(MultiThreadedHttpConnectionManager.java:1361) [commons-httpclient.jar:] > at org.apache.commons.httpclient.HttpMethodDirector.executeWithRetry(HttpMethodDirector.java:387) [commons-httpclient.jar:] > at org.apache.commons.httpclient.HttpMethodDirector.executeMethod(HttpMethodDirector.java:171) [commons-httpclient.jar:] > at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:397) [commons-httpclient.jar:] > at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:323) [commons-httpclient.jar:] > at org.apache.xmlrpc.client.XmlRpcCommonsTransport.writeRequest(XmlRpcCommonsTransport.java:227) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcStreamTransport.sendRequest(XmlRpcStreamTransport.java:151) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcHttpTransport.sendRequest(XmlRpcHttpTransport.java:143) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcClientWorker.execute(XmlRpcClientWorker.java:56) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcClient.execute(XmlRpcClient.java:167) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcClient.execute(XmlRpcClient.java:137) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcClient.execute(XmlRpcClient.java:126) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.util.ClientFactory$1.invoke(ClientFactory.java:140) [xmlrpc-client.jar:3.1.3] > at com.sun.proxy.$Proxy136.getVdsStats(Unknown Source) > at sun.reflect.GeneratedMethodAccessor85.invoke(Unknown Source) [:1.7.0_55] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_55] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_55] > at org.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcUtils$AsyncProxy$InternalCallable.call(XmlRpcUtils.java:242) [vdsbroker.jar:] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_55] > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_55] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_55] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_55] > >2014-07-01 06:19:17,646 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-30) Failed to refresh VDS , vds = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 : orange-vdsd.qa.lab.tlv.redhat.com, VDS Network Error, continuing. >java.net.ConnectException: Connection refused >2014-07-01 06:19:18,353 INFO [org.ovirt.engine.core.vdsbroker.storage.StoragePoolDomainHelper] (DefaultQuartzScheduler_Worker-97) Storage Pool 00000002-0002-0002-0002-000000000251 - Updating Storage Domain 86ecda08-9abd-4a3c-b13f-de0f3746ce82 status from Active to Unknown, reason : no reporting hosts >2014-07-01 06:19:18,360 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStatusVDSCommand] (DefaultQuartzScheduler_Worker-97) Command SpmStatusVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, storagePoolId = 00000002-0002-0002-0002-000000000251) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-07-01 06:19:18,365 INFO [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (DefaultQuartzScheduler_Worker-97) [7a774159] Running command: SetStoragePoolStatusCommand internal: true. Entities affected : ID: 00000002-0002-0002-0002-000000000251 Type: StoragePool >2014-07-01 06:19:18,377 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-97) [7a774159] Correlation ID: 7a774159, Call Stack: null, Custom Event ID: -1, Message: Invalid status on Data Center Default. Setting Data Center status to Non Responsive (On host orange-vdsd.qa.lab.tlv.redhat.com, Error: Network error during communication with the Host.). >2014-07-01 06:19:18,384 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (DefaultQuartzScheduler_Worker-97) [7a774159] IrsBroker::Failed::GetStoragePoolInfoVDS due to: ConnectException: Connection refused >2014-07-01 06:19:20,651 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-42) Command GetCapabilitiesVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vds=Host[orange-vdsd.qa.lab.tlv.redhat.com,8749c6c4-ffd0-4f2d-a877-09a832e7a8f6]) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-07-01 06:19:20,652 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-42) Host orange-vdsd.qa.lab.tlv.redhat.com is not responding. It will stay in Connecting state for a grace period of $80 seconds and after that an attempt to fence the host will be issued. >2014-07-01 06:19:20,655 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-42) Failure to refresh Vds runtime info: org.ovirt.engine.core.vdsbroker.vdsbroker.VDSNetworkException: java.net.ConnectException: Connection refused > at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.createNetworkException(VdsBrokerCommand.java:126) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.executeVDSCommand(VdsBrokerCommand.java:101) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.VDSCommandBase.executeCommand(VDSCommandBase.java:56) [vdsbroker.jar:] > at org.ovirt.engine.core.dal.VdcCommandBase.execute(VdcCommandBase.java:31) [dal.jar:] > at org.ovirt.engine.core.vdsbroker.VdsManager.refreshCapabilities(VdsManager.java:566) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.refreshVdsRunTimeInfo(VdsUpdateRunTimeInfo.java:628) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.refresh(VdsUpdateRunTimeInfo.java:485) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.VdsManager.onTimer(VdsManager.java:235) [vdsbroker.jar:] > at sun.reflect.GeneratedMethodAccessor59.invoke(Unknown Source) [:1.7.0_55] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_55] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_55] > at org.ovirt.engine.core.utils.timer.JobWrapper.execute(JobWrapper.java:60) [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.net.ConnectException: Connection refused > at java.net.PlainSocketImpl.socketConnect(Native Method) [rt.jar:1.7.0_55] > at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339) [rt.jar:1.7.0_55] > at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200) [rt.jar:1.7.0_55] > at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182) [rt.jar:1.7.0_55] > at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) [rt.jar:1.7.0_55] > at java.net.Socket.connect(Socket.java:579) [rt.jar:1.7.0_55] > at sun.security.ssl.SSLSocketImpl.connect(SSLSocketImpl.java:618) [jsse.jar:1.7.0_55] > at org.ovirt.engine.core.utils.ssl.AuthSSLProtocolSocketFactory.createSocket(AuthSSLProtocolSocketFactory.java:288) [utils.jar:] > at org.apache.commons.httpclient.HttpConnection.open(HttpConnection.java:707) [commons-httpclient.jar:] > at org.apache.commons.httpclient.MultiThreadedHttpConnectionManager$HttpConnectionAdapter.open(MultiThreadedHttpConnectionManager.java:1361) [commons-httpclient.jar:] > at org.apache.commons.httpclient.HttpMethodDirector.executeWithRetry(HttpMethodDirector.java:387) [commons-httpclient.jar:] > at org.apache.commons.httpclient.HttpMethodDirector.executeMethod(HttpMethodDirector.java:171) [commons-httpclient.jar:] > at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:397) [commons-httpclient.jar:] > at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:323) [commons-httpclient.jar:] > at org.apache.xmlrpc.client.XmlRpcCommonsTransport.writeRequest(XmlRpcCommonsTransport.java:227) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcStreamTransport.sendRequest(XmlRpcStreamTransport.java:151) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcHttpTransport.sendRequest(XmlRpcHttpTransport.java:143) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcClientWorker.execute(XmlRpcClientWorker.java:56) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcClient.execute(XmlRpcClient.java:167) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcClient.execute(XmlRpcClient.java:137) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcClient.execute(XmlRpcClient.java:126) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.util.ClientFactory$1.invoke(ClientFactory.java:140) [xmlrpc-client.jar:3.1.3] > at com.sun.proxy.$Proxy136.getVdsCapabilities(Unknown Source) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_55] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_55] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_55] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_55] > at org.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcUtils$AsyncProxy$InternalCallable.call(XmlRpcUtils.java:242) [vdsbroker.jar:] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_55] > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_55] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_55] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_55] > >2014-07-01 06:19:20,678 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-42) Failed to refresh VDS , vds = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 : orange-vdsd.qa.lab.tlv.redhat.com, VDS Network Error, continuing. >java.net.ConnectException: Connection refused >2014-07-01 06:19:23,684 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-55) Command GetCapabilitiesVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vds=Host[orange-vdsd.qa.lab.tlv.redhat.com,8749c6c4-ffd0-4f2d-a877-09a832e7a8f6]) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-07-01 06:19:23,685 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-55) Host orange-vdsd.qa.lab.tlv.redhat.com is not responding. It will stay in Connecting state for a grace period of $80 seconds and after that an attempt to fence the host will be issued. >2014-07-01 06:19:23,688 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-55) Failure to refresh Vds runtime info: org.ovirt.engine.core.vdsbroker.vdsbroker.VDSNetworkException: java.net.ConnectException: Connection refused > at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.createNetworkException(VdsBrokerCommand.java:126) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.executeVDSCommand(VdsBrokerCommand.java:101) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.VDSCommandBase.executeCommand(VDSCommandBase.java:56) [vdsbroker.jar:] > at org.ovirt.engine.core.dal.VdcCommandBase.execute(VdcCommandBase.java:31) [dal.jar:] > at org.ovirt.engine.core.vdsbroker.VdsManager.refreshCapabilities(VdsManager.java:566) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.refreshVdsRunTimeInfo(VdsUpdateRunTimeInfo.java:628) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.refresh(VdsUpdateRunTimeInfo.java:485) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.VdsManager.onTimer(VdsManager.java:235) [vdsbroker.jar:] > at sun.reflect.GeneratedMethodAccessor59.invoke(Unknown Source) [:1.7.0_55] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_55] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_55] > at org.ovirt.engine.core.utils.timer.JobWrapper.execute(JobWrapper.java:60) [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.net.ConnectException: Connection refused > at java.net.PlainSocketImpl.socketConnect(Native Method) [rt.jar:1.7.0_55] > at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339) [rt.jar:1.7.0_55] > at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200) [rt.jar:1.7.0_55] > at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182) [rt.jar:1.7.0_55] > at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) [rt.jar:1.7.0_55] > at java.net.Socket.connect(Socket.java:579) [rt.jar:1.7.0_55] > at sun.security.ssl.SSLSocketImpl.connect(SSLSocketImpl.java:618) [jsse.jar:1.7.0_55] > at org.ovirt.engine.core.utils.ssl.AuthSSLProtocolSocketFactory.createSocket(AuthSSLProtocolSocketFactory.java:288) [utils.jar:] > at org.apache.commons.httpclient.HttpConnection.open(HttpConnection.java:707) [commons-httpclient.jar:] > at org.apache.commons.httpclient.MultiThreadedHttpConnectionManager$HttpConnectionAdapter.open(MultiThreadedHttpConnectionManager.java:1361) [commons-httpclient.jar:] > at org.apache.commons.httpclient.HttpMethodDirector.executeWithRetry(HttpMethodDirector.java:387) [commons-httpclient.jar:] > at org.apache.commons.httpclient.HttpMethodDirector.executeMethod(HttpMethodDirector.java:171) [commons-httpclient.jar:] > at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:397) [commons-httpclient.jar:] > at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:323) [commons-httpclient.jar:] > at org.apache.xmlrpc.client.XmlRpcCommonsTransport.writeRequest(XmlRpcCommonsTransport.java:227) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcStreamTransport.sendRequest(XmlRpcStreamTransport.java:151) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcHttpTransport.sendRequest(XmlRpcHttpTransport.java:143) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcClientWorker.execute(XmlRpcClientWorker.java:56) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcClient.execute(XmlRpcClient.java:167) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcClient.execute(XmlRpcClient.java:137) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcClient.execute(XmlRpcClient.java:126) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.util.ClientFactory$1.invoke(ClientFactory.java:140) [xmlrpc-client.jar:3.1.3] > at com.sun.proxy.$Proxy136.getVdsCapabilities(Unknown Source) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_55] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_55] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_55] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_55] > at org.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcUtils$AsyncProxy$InternalCallable.call(XmlRpcUtils.java:242) [vdsbroker.jar:] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_55] > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_55] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_55] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_55] > >2014-07-01 06:19:23,703 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-55) Failed to refresh VDS , vds = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 : orange-vdsd.qa.lab.tlv.redhat.com, VDS Network Error, continuing. >java.net.ConnectException: Connection refused >2014-07-01 06:19:26,709 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-54) Command GetCapabilitiesVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vds=Host[orange-vdsd.qa.lab.tlv.redhat.com,8749c6c4-ffd0-4f2d-a877-09a832e7a8f6]) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-07-01 06:19:26,710 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-54) Host orange-vdsd.qa.lab.tlv.redhat.com is not responding. It will stay in Connecting state for a grace period of $80 seconds and after that an attempt to fence the host will be issued. >2014-07-01 06:19:26,710 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-54) Failure to refresh Vds runtime info: org.ovirt.engine.core.vdsbroker.vdsbroker.VDSNetworkException: java.net.ConnectException: Connection refused > at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.createNetworkException(VdsBrokerCommand.java:126) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.executeVDSCommand(VdsBrokerCommand.java:101) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.VDSCommandBase.executeCommand(VDSCommandBase.java:56) [vdsbroker.jar:] > at org.ovirt.engine.core.dal.VdcCommandBase.execute(VdcCommandBase.java:31) [dal.jar:] > at org.ovirt.engine.core.vdsbroker.VdsManager.refreshCapabilities(VdsManager.java:566) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.refreshVdsRunTimeInfo(VdsUpdateRunTimeInfo.java:628) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.refresh(VdsUpdateRunTimeInfo.java:485) [vdsbroker.jar:] > at org.ovirt.engine.core.vdsbroker.VdsManager.onTimer(VdsManager.java:235) [vdsbroker.jar:] > at sun.reflect.GeneratedMethodAccessor59.invoke(Unknown Source) [:1.7.0_55] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_55] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_55] > at org.ovirt.engine.core.utils.timer.JobWrapper.execute(JobWrapper.java:60) [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.net.ConnectException: Connection refused > at java.net.PlainSocketImpl.socketConnect(Native Method) [rt.jar:1.7.0_55] > at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339) [rt.jar:1.7.0_55] > at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200) [rt.jar:1.7.0_55] > at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182) [rt.jar:1.7.0_55] > at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) [rt.jar:1.7.0_55] > at java.net.Socket.connect(Socket.java:579) [rt.jar:1.7.0_55] > at sun.security.ssl.SSLSocketImpl.connect(SSLSocketImpl.java:618) [jsse.jar:1.7.0_55] > at org.ovirt.engine.core.utils.ssl.AuthSSLProtocolSocketFactory.createSocket(AuthSSLProtocolSocketFactory.java:288) [utils.jar:] > at org.apache.commons.httpclient.HttpConnection.open(HttpConnection.java:707) [commons-httpclient.jar:] > at org.apache.commons.httpclient.MultiThreadedHttpConnectionManager$HttpConnectionAdapter.open(MultiThreadedHttpConnectionManager.java:1361) [commons-httpclient.jar:] > at org.apache.commons.httpclient.HttpMethodDirector.executeWithRetry(HttpMethodDirector.java:387) [commons-httpclient.jar:] > at org.apache.commons.httpclient.HttpMethodDirector.executeMethod(HttpMethodDirector.java:171) [commons-httpclient.jar:] > at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:397) [commons-httpclient.jar:] > at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:323) [commons-httpclient.jar:] > at org.apache.xmlrpc.client.XmlRpcCommonsTransport.writeRequest(XmlRpcCommonsTransport.java:227) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcStreamTransport.sendRequest(XmlRpcStreamTransport.java:151) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcHttpTransport.sendRequest(XmlRpcHttpTransport.java:143) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcClientWorker.execute(XmlRpcClientWorker.java:56) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcClient.execute(XmlRpcClient.java:167) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcClient.execute(XmlRpcClient.java:137) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.XmlRpcClient.execute(XmlRpcClient.java:126) [xmlrpc-client.jar:3.1.3] > at org.apache.xmlrpc.client.util.ClientFactory$1.invoke(ClientFactory.java:140) [xmlrpc-client.jar:3.1.3] > at com.sun.proxy.$Proxy136.getVdsCapabilities(Unknown Source) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_55] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_55] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_55] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_55] > at org.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcUtils$AsyncProxy$InternalCallable.call(XmlRpcUtils.java:242) [vdsbroker.jar:] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_55] > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_55] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_55] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_55] > >2014-07-01 06:19:26,725 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-54) Failed to refresh VDS , vds = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 : orange-vdsd.qa.lab.tlv.redhat.com, VDS Network Error, continuing. >java.net.ConnectException: Connection refused >2014-07-01 06:19:28,397 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStatusVDSCommand] (DefaultQuartzScheduler_Worker-68) Command SpmStatusVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, storagePoolId = 00000002-0002-0002-0002-000000000251) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-07-01 06:19:28,407 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (DefaultQuartzScheduler_Worker-68) IrsBroker::Failed::GetStoragePoolInfoVDS due to: ConnectException: Connection refused >2014-07-01 06:19:29,834 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-62) Command org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand return value > org.ovirt.engine.core.vdsbroker.vdsbroker.VDSInfoReturnForXmlRpc@500a9637 >2014-07-01 06:19:29,835 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-62) HostName = orange-vdsd.qa.lab.tlv.redhat.com >2014-07-01 06:19:29,835 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-62) Command GetCapabilitiesVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vds=Host[orange-vdsd.qa.lab.tlv.redhat.com,8749c6c4-ffd0-4f2d-a877-09a832e7a8f6]) execution failed. Exception: VDSRecoveringException: Recovering from crash or Initializing >2014-07-01 06:19:29,844 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-62) Correlation ID: null, Call Stack: org.ovirt.engine.core.vdsbroker.vdsbroker.VDSRecoveringException: Recovering from crash or Initializing > at org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase.proceedProxyReturnValue(BrokerCommandBase.java:42) > at org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand.executeVdsBrokerCommand(GetCapabilitiesVDSCommand.java:16) > at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.executeVDSCommand(VdsBrokerCommand.java:96) > at org.ovirt.engine.core.vdsbroker.VDSCommandBase.executeCommand(VDSCommandBase.java:56) > at org.ovirt.engine.core.dal.VdcCommandBase.execute(VdcCommandBase.java:31) > at org.ovirt.engine.core.vdsbroker.VdsManager.refreshCapabilities(VdsManager.java:566) > at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.refreshVdsRunTimeInfo(VdsUpdateRunTimeInfo.java:628) > at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.refresh(VdsUpdateRunTimeInfo.java:485) > at org.ovirt.engine.core.vdsbroker.VdsManager.onTimer(VdsManager.java:235) > at sun.reflect.GeneratedMethodAccessor59.invoke(Unknown Source) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:606) > at org.ovirt.engine.core.utils.timer.JobWrapper.execute(JobWrapper.java:60) > at org.quartz.core.JobRunShell.run(JobRunShell.java:213) > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:557) >, Custom Event ID: -1, Message: Host orange-vdsd.qa.lab.tlv.redhat.com is initializing. Message: Recovering from crash or Initializing >2014-07-01 06:19:29,848 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-62) Failed to refresh VDS , vds = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 : orange-vdsd.qa.lab.tlv.redhat.com, error = Recovering from crash or Initializing, continuing. >2014-07-01 06:19:33,609 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-69) START, GetHardwareInfoVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vds=Host[orange-vdsd.qa.lab.tlv.redhat.com,8749c6c4-ffd0-4f2d-a877-09a832e7a8f6]), log id: 79f89414 >2014-07-01 06:19:33,785 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-69) FINISH, GetHardwareInfoVDSCommand, log id: 79f89414 >2014-07-01 06:19:33,801 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (DefaultQuartzScheduler_Worker-69) [25d9580c] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 Type: VDS >2014-07-01 06:19:33,847 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-69) [25d9580c] Cancelling the recovery from crash timer for VDS 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 because vds started initializing >2014-07-01 06:19:33,854 INFO [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (DefaultQuartzScheduler_Worker-69) [2b68945d] Running command: InitVdsOnUpCommand internal: true. Entities affected : ID: 00000002-0002-0002-0002-000000000251 Type: StoragePool >2014-07-01 06:19:33,858 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-69) [2b68945d] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Kdump detection is enabled for host 'orange-vdsd.qa.lab.tlv.redhat.com', but kdump is not configured properly on host. >2014-07-01 06:19:33,869 INFO [org.ovirt.engine.core.bll.storage.ConnectHostToStoragePoolServersCommand] (DefaultQuartzScheduler_Worker-69) [1ff54a2c] Running command: ConnectHostToStoragePoolServersCommand internal: true. Entities affected : ID: 00000002-0002-0002-0002-000000000251 Type: StoragePool >2014-07-01 06:19:33,883 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (DefaultQuartzScheduler_Worker-69) [1ff54a2c] START, ConnectStorageServerVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, storagePoolId = 00000002-0002-0002-0002-000000000251, storageType = NFS, connectionList = [{ id: 1214142a-5bc0-4826-a004-d45481505028, connection: 10.35.160.108:/RHEV/myakove_testday_ovirt, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 73190c94 >2014-07-01 06:19:33,928 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (DefaultQuartzScheduler_Worker-69) [1ff54a2c] FINISH, ConnectStorageServerVDSCommand, return: {1214142a-5bc0-4826-a004-d45481505028=0}, log id: 73190c94 >2014-07-01 06:19:33,929 INFO [org.ovirt.engine.core.bll.storage.ConnectHostToStoragePoolServersCommand] (DefaultQuartzScheduler_Worker-69) [1ff54a2c] Host orange-vdsd.qa.lab.tlv.redhat.com storage connection was succeeded >2014-07-01 06:19:33,933 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-8-thread-14) START, ConnectStoragePoolVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vdsId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, storagePoolId = 00000002-0002-0002-0002-000000000251, masterVersion = 1), log id: 5921ed74 >2014-07-01 06:19:34,924 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-8-thread-14) FINISH, ConnectStoragePoolVDSCommand, log id: 5921ed74 >2014-07-01 06:19:34,940 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (DefaultQuartzScheduler_Worker-69) [1ff54a2c] START, SetMOMPolicyParametersVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6), log id: 1db9a77 >2014-07-01 06:19:35,138 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (DefaultQuartzScheduler_Worker-69) [1ff54a2c] FINISH, SetMOMPolicyParametersVDSCommand, log id: 1db9a77 >2014-07-01 06:19:35,142 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-69) [1ff54a2c] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Status of host orange-vdsd.qa.lab.tlv.redhat.com was set to Up. >2014-07-01 06:19:35,146 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-69) [1ff54a2c] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host orange-vdsd.qa.lab.tlv.redhat.com. >2014-07-01 06:19:35,167 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (DefaultQuartzScheduler_Worker-69) [5ec17d4c] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 Type: VDS >2014-07-01 06:19:38,486 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSCommand] (DefaultQuartzScheduler_Worker-80) Command org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSCommand return value > >TaskStatusListReturnForXmlRpc [mStatus=StatusForXmlRpc [mCode=654, mMessage=Not SPM]] > >2014-07-01 06:19:38,487 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSCommand] (DefaultQuartzScheduler_Worker-80) HostName = orange-vdsd.qa.lab.tlv.redhat.com >2014-07-01 06:19:38,487 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSCommand] (DefaultQuartzScheduler_Worker-80) Command HSMGetAllTasksStatusesVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6) execution failed. Exception: IRSNonOperationalException: IRSGenericException: IRSErrorException: IRSNonOperationalException: Not SPM >2014-07-01 06:19:38,554 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (DefaultQuartzScheduler_Worker-80) hostFromVds::selectedVds - orange-vdsd.qa.lab.tlv.redhat.com, spmStatus Free, storage pool Default >2014-07-01 06:19:38,557 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (DefaultQuartzScheduler_Worker-80) starting spm on vds orange-vdsd.qa.lab.tlv.redhat.com, storage pool Default, prevId -1, LVER -1 >2014-07-01 06:19:38,558 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (DefaultQuartzScheduler_Worker-80) START, SpmStartVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, storagePoolId = 00000002-0002-0002-0002-000000000251, prevId=-1, prevLVER=-1, storagePoolFormatType=V3, recoveryMode=Manual, SCSIFencing=false), log id: 4d710f54 >2014-07-01 06:19:38,618 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (DefaultQuartzScheduler_Worker-80) spmStart polling started: taskId = 23e73726-54fa-44ec-b77a-0279f21abf8c >2014-07-01 06:19:39,635 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (DefaultQuartzScheduler_Worker-80) spmStart polling ended: taskId = 23e73726-54fa-44ec-b77a-0279f21abf8c task status = finished >2014-07-01 06:19:39,704 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (DefaultQuartzScheduler_Worker-80) spmStart polling ended, spm status: SPM >2014-07-01 06:19:39,705 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (DefaultQuartzScheduler_Worker-80) START, HSMClearTaskVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, taskId=23e73726-54fa-44ec-b77a-0279f21abf8c), log id: 66c639ff >2014-07-01 06:19:39,763 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (DefaultQuartzScheduler_Worker-80) FINISH, HSMClearTaskVDSCommand, log id: 66c639ff >2014-07-01 06:19:39,763 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (DefaultQuartzScheduler_Worker-80) FINISH, SpmStartVDSCommand, return: org.ovirt.engine.core.common.businessentities.SpmStatusResult@1daba830, log id: 4d710f54 >2014-07-01 06:19:39,766 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (DefaultQuartzScheduler_Worker-80) Initialize Irs proxy from vds: orange-vdsd.qa.lab.tlv.redhat.com >2014-07-01 06:19:39,770 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-80) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Storage Pool Manager runs on Host orange-vdsd.qa.lab.tlv.redhat.com (Address: orange-vdsd.qa.lab.tlv.redhat.com). >2014-07-01 06:19:39,788 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-45) START, SPMGetAllTasksInfoVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000251, ignoreFailoverLimit = false), log id: 4f4d74b8 >2014-07-01 06:19:39,829 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-45) -- executeIrsBrokerCommand: Attempting on storage pool 00000002-0002-0002-0002-000000000251 >2014-07-01 06:19:39,831 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-45) START, HSMGetAllTasksInfoVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6), log id: 3c114720 >2014-07-01 06:19:39,839 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-45) FINISH, HSMGetAllTasksInfoVDSCommand, return: [], log id: 3c114720 >2014-07-01 06:19:39,839 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-45) FINISH, SPMGetAllTasksInfoVDSCommand, return: [], log id: 4f4d74b8 >2014-07-01 06:19:39,840 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-8-thread-45) Discovered no tasks on Storage Pool Default >2014-07-01 06:20:55,375 INFO [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-8) [1c0c4bff] Lock Acquired to object EngineLock [exclusiveLocks= key: 7e1fb1c7-700f-432d-b982-06b4cd3c436e value: VM >, sharedLocks= ] >2014-07-01 06:20:55,423 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-8) [1c0c4bff] START, IsVmDuringInitiatingVDSCommand( vmId = 7e1fb1c7-700f-432d-b982-06b4cd3c436e), log id: 3483dcc2 >2014-07-01 06:20:55,424 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-8) [1c0c4bff] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 3483dcc2 >2014-07-01 06:20:55,470 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (ajp--127.0.0.1-8702-8) [1c0c4bff] Candidate host orange-vdsd.qa.lab.tlv.redhat.com (8749c6c4-ffd0-4f2d-a877-09a832e7a8f6) was filtered out by VAR__FILTERTYPE__INTERNAL filter Network >2014-07-01 06:20:55,471 WARN [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-8) [1c0c4bff] CanDoAction of action RunVm failed. Reasons:VAR__ACTION__RUN,VAR__TYPE__VM,SCHEDULING_ALL_HOSTS_FILTERED_OUT,VAR__FILTERTYPE__INTERNAL,$hostName orange-vdsd.qa.lab.tlv.redhat.com,$filterName Network,$networkNames ,VAR__DETAIL__NETWORK_MISSING,SCHEDULING_HOST_FILTERED_REASON_WITH_DETAIL >2014-07-01 06:20:55,472 INFO [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-8) [1c0c4bff] Lock freed to object EngineLock [exclusiveLocks= key: 7e1fb1c7-700f-432d-b982-06b4cd3c436e value: VM >, sharedLocks= ] >2014-07-01 06:21:30,942 INFO [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-3) [29cb54e0] Lock Acquired to object EngineLock [exclusiveLocks= key: 7e1fb1c7-700f-432d-b982-06b4cd3c436e value: VM >, sharedLocks= ] >2014-07-01 06:21:31,019 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-3) [29cb54e0] START, IsVmDuringInitiatingVDSCommand( vmId = 7e1fb1c7-700f-432d-b982-06b4cd3c436e), log id: cfc2140 >2014-07-01 06:21:31,020 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-3) [29cb54e0] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: cfc2140 >2014-07-01 06:21:31,042 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (ajp--127.0.0.1-8702-3) [29cb54e0] Candidate host orange-vdsd.qa.lab.tlv.redhat.com (8749c6c4-ffd0-4f2d-a877-09a832e7a8f6) was filtered out by VAR__FILTERTYPE__INTERNAL filter Network >2014-07-01 06:21:31,043 WARN [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-3) [29cb54e0] CanDoAction of action RunVm failed. Reasons:VAR__ACTION__RUN,VAR__TYPE__VM,SCHEDULING_ALL_HOSTS_FILTERED_OUT,VAR__FILTERTYPE__INTERNAL,$hostName orange-vdsd.qa.lab.tlv.redhat.com,$filterName Network,$networkNames ,VAR__DETAIL__NETWORK_MISSING,SCHEDULING_HOST_FILTERED_REASON_WITH_DETAIL >2014-07-01 06:21:31,044 INFO [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-3) [29cb54e0] Lock freed to object EngineLock [exclusiveLocks= key: 7e1fb1c7-700f-432d-b982-06b4cd3c436e value: VM >, sharedLocks= ] >2014-07-01 06:22:14,807 INFO [org.ovirt.engine.core.bll.network.dc.UpdateNetworkCommand] (ajp--127.0.0.1-8702-6) [39287281] Running command: UpdateNetworkCommand internal: false. Entities affected : ID: 66367911-a0d0-4187-b46d-13c3bc065348 Type: Network >2014-07-01 06:22:14,854 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-6) Correlation ID: 39287281, Call Stack: null, Custom Event ID: -1, Message: Network vm_net was updated on Data Center: Default >2014-07-01 06:22:30,265 INFO [org.ovirt.engine.core.bll.network.host.SetupNetworksCommand] (ajp--127.0.0.1-8702-5) [584fe46] Running command: SetupNetworksCommand internal: false. Entities affected : ID: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 Type: VDS >2014-07-01 06:22:30,267 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp--127.0.0.1-8702-5) [584fe46] START, SetupNetworksVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, force=false, checkConnectivity=true, conectivityTimeout=120, > networks=[vm_net {id=66367911-a0d0-4187-b46d-13c3bc065348, description=null, comment=null, subnet=null, gateway=null, type=null, vlanId=0, stp=false, dataCenterId=00000002-0002-0002-0002-000000000251, mtu=0, vmNetwork=true, cluster=NetworkCluster {id={clusterId=null, networkId=null}, status=NON_OPERATIONAL, display=false, required=true, migration=false}, providedBy=null, label=null, qosId=null}], > bonds=[], > interfaces=[eth0 {id=44c228c6-2c2a-4f7f-899a-ab4ce9231100, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth0, macAddress=00:16:36:76:3e:ab, networkName=ovirtmgmt, bondName=null, bootProtocol=NONE, address=10.35.128.18, subnet=255.255.255.0, gateway=10.35.128.254, mtu=1500, bridged=true, speed=0, type=2, networkImplementationDetails={inSync=true, managed=true}}, > eth2 {id=0a752445-4430-4e6d-b51d-aa0139234261, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth2, macAddress=00:16:36:76:3e:a9, networkName=null, bondName=null, bootProtocol=NONE, address=, subnet=, gateway=null, mtu=1500, bridged=false, speed=1000, type=0, networkImplementationDetails=null}, > eth1 {id=34ede921-933f-4667-9931-9aa3988443c0, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth1, macAddress=00:16:36:76:3e:ac, networkName=null, bondName=null, bootProtocol=NONE, address=, subnet=, gateway=null, mtu=1500, bridged=false, speed=0, type=0, networkImplementationDetails=null}, > eth3 {id=4c415151-0484-4030-9d12-14bde9ba1e47, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth3, macAddress=00:16:36:76:3e:aa, networkName=null, bondName=null, bootProtocol=NONE, address=, subnet=, gateway=null, mtu=1500, bridged=false, speed=1000, type=0, networkImplementationDetails=null}, > eth1.0 {id=null, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, macAddress=null, networkName=vm_net, baseInterface=eth1, vlanId=0, bonded=null, bondName=null, bondOptions=null, bootProtocol=NONE, address=null, subnet=null, gateway=null, mtu=0, bridged=true, speed=null, type=0, networkImplementationDetails=null, qos=null qosOverridden=false, labels=null, customProperties=null}], > removedNetworks=[], > removedBonds=[]), log id: 6644ee9a >2014-07-01 06:22:30,318 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp--127.0.0.1-8702-5) [584fe46] FINISH, SetupNetworksVDSCommand, log id: 6644ee9a >2014-07-01 06:22:33,794 INFO [org.ovirt.engine.core.bll.network.host.CommitNetworkChangesCommand] (ajp--127.0.0.1-8702-9) [171b5ca8] Running command: CommitNetworkChangesCommand internal: false. Entities affected : ID: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 Type: VDS >2014-07-01 06:22:33,795 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetSafeNetworkConfigVDSCommand] (ajp--127.0.0.1-8702-9) [171b5ca8] START, SetSafeNetworkConfigVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6), log id: 4bdc2d94 >2014-07-01 06:22:33,839 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetSafeNetworkConfigVDSCommand] (ajp--127.0.0.1-8702-9) [171b5ca8] FINISH, SetSafeNetworkConfigVDSCommand, log id: 4bdc2d94 >2014-07-01 06:22:33,854 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-9) [171b5ca8] Correlation ID: 171b5ca8, Job ID: d0246040-a055-4498-a4f5-ab959c22c1ca, Call Stack: null, Custom Event ID: -1, Message: Network changes were saved on host orange-vdsd.qa.lab.tlv.redhat.com >2014-07-01 06:24:06,150 INFO [org.ovirt.engine.core.bll.network.dc.AddNetworkCommand] (ajp--127.0.0.1-8702-3) [3d810bff] Lock Acquired to object EngineLock [exclusiveLocks= key: vm_nett value: NETWORK >, sharedLocks= ] >2014-07-01 06:24:06,156 INFO [org.ovirt.engine.core.bll.network.dc.AddNetworkCommand] (ajp--127.0.0.1-8702-3) [3d810bff] Running command: AddNetworkCommand internal: false. Entities affected : ID: 00000002-0002-0002-0002-000000000251 Type: StoragePool >2014-07-01 06:24:06,179 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [3d810bff] Correlation ID: 3d810bff, Call Stack: null, Custom Event ID: -1, Message: Network vm_nett was added to Data Center: Default >2014-07-01 06:24:06,180 INFO [org.ovirt.engine.core.bll.network.dc.AddNetworkCommand] (ajp--127.0.0.1-8702-3) [3d810bff] Lock freed to object EngineLock [exclusiveLocks= key: vm_nett value: NETWORK >, sharedLocks= ] >2014-07-01 06:24:06,371 INFO [org.ovirt.engine.core.bll.network.vm.AddVnicProfileCommand] (ajp--127.0.0.1-8702-11) [2ed774a9] Running command: AddVnicProfileCommand internal: false. Entities affected : ID: 67bddd9b-c1ea-4ff9-a606-62948e887e3e Type: Network >2014-07-01 06:24:06,379 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-11) [2ed774a9] Correlation ID: 2ed774a9, Call Stack: null, Custom Event ID: -1, Message: VM network interface profile vm_nett was added to network vm_nett in Data Center: Default. (User: admin) >2014-07-01 06:24:06,558 INFO [org.ovirt.engine.core.bll.network.cluster.AttachNetworkToVdsGroupCommand] (org.ovirt.thread.pool-8-thread-39) [3474043c] Running command: AttachNetworkToVdsGroupCommand internal: false. Entities affected : ID: 67bddd9b-c1ea-4ff9-a606-62948e887e3e Type: Network >2014-07-01 06:24:06,563 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-39) [3474043c] Correlation ID: 3474043c, Call Stack: null, Custom Event ID: -1, Message: Network vm_nett attached to Cluster Default >2014-07-01 06:24:27,437 INFO [org.ovirt.engine.core.bll.network.dc.UpdateNetworkCommand] (ajp--127.0.0.1-8702-12) [417504f] Running command: UpdateNetworkCommand internal: false. Entities affected : ID: 67bddd9b-c1ea-4ff9-a606-62948e887e3e Type: Network >2014-07-01 06:24:27,458 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-12) [417504f] Correlation ID: 417504f, Call Stack: null, Custom Event ID: -1, Message: Network vm_nett was updated on Data Center: Default >2014-07-01 06:24:43,376 INFO [org.ovirt.engine.core.bll.network.host.SetupNetworksCommand] (ajp--127.0.0.1-8702-5) [356b5b9f] Running command: SetupNetworksCommand internal: false. Entities affected : ID: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 Type: VDS >2014-07-01 06:24:43,377 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp--127.0.0.1-8702-5) [356b5b9f] START, SetupNetworksVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, force=false, checkConnectivity=true, conectivityTimeout=120, > networks=[vm_nett {id=67bddd9b-c1ea-4ff9-a606-62948e887e3e, description=null, comment=null, subnet=null, gateway=null, type=null, vlanId=null, stp=false, dataCenterId=00000002-0002-0002-0002-000000000251, mtu=0, vmNetwork=true, cluster=NetworkCluster {id={clusterId=null, networkId=null}, status=OPERATIONAL, display=false, required=false, migration=false}, providedBy=null, label=null, qosId=null}], > bonds=[], > interfaces=[eth0 {id=44c228c6-2c2a-4f7f-899a-ab4ce9231100, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth0, macAddress=00:16:36:76:3e:ab, networkName=ovirtmgmt, bondName=null, bootProtocol=NONE, address=10.35.128.18, subnet=255.255.255.0, gateway=10.35.128.254, mtu=1500, bridged=true, speed=0, type=2, networkImplementationDetails={inSync=true, managed=true}}, > eth2 {id=0a752445-4430-4e6d-b51d-aa0139234261, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth2, macAddress=00:16:36:76:3e:a9, networkName=vm_nett, bondName=null, bootProtocol=NONE, address=, subnet=, gateway=null, mtu=0, bridged=true, speed=1000, type=0, networkImplementationDetails=null}, > eth1 {id=34ede921-933f-4667-9931-9aa3988443c0, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth1, macAddress=00:16:36:76:3e:ac, networkName=null, bondName=null, bootProtocol=NONE, address=, subnet=, gateway=null, mtu=1500, bridged=false, speed=0, type=0, networkImplementationDetails=null}, > eth3 {id=4c415151-0484-4030-9d12-14bde9ba1e47, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth3, macAddress=00:16:36:76:3e:aa, networkName=null, bondName=null, bootProtocol=NONE, address=, subnet=, gateway=null, mtu=1500, bridged=false, speed=1000, type=0, networkImplementationDetails=null}, > eth1.0 {id=6426d1b2-57a1-4af3-8111-3cf4944e17a2, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth1.0, networkName=vm_net, vlanId=0, bootProtocol=NONE, address=, subnet=, gateway=, mtu=1500, bridged=true, type=0, networkImplementationDetails={inSync=true, managed=true}}], > removedNetworks=[], > removedBonds=[]), log id: 1a968ed7 >2014-07-01 06:24:43,387 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp--127.0.0.1-8702-5) [356b5b9f] FINISH, SetupNetworksVDSCommand, log id: 1a968ed7 >2014-07-01 06:24:46,693 INFO [org.ovirt.engine.core.bll.network.host.CommitNetworkChangesCommand] (ajp--127.0.0.1-8702-1) [293734cb] Running command: CommitNetworkChangesCommand internal: false. Entities affected : ID: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 Type: VDS >2014-07-01 06:24:46,696 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetSafeNetworkConfigVDSCommand] (ajp--127.0.0.1-8702-1) [293734cb] START, SetSafeNetworkConfigVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6), log id: 14cdab86 >2014-07-01 06:24:46,741 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetSafeNetworkConfigVDSCommand] (ajp--127.0.0.1-8702-1) [293734cb] FINISH, SetSafeNetworkConfigVDSCommand, log id: 14cdab86 >2014-07-01 06:24:46,747 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-1) [293734cb] Correlation ID: 293734cb, Job ID: 1aed4661-e9f5-4693-8e5e-906611b385c9, Call Stack: null, Custom Event ID: -1, Message: Network changes were saved on host orange-vdsd.qa.lab.tlv.redhat.com >2014-07-01 06:24:56,586 INFO [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-1) [94d0a7f] Lock Acquired to object EngineLock [exclusiveLocks= key: 7e1fb1c7-700f-432d-b982-06b4cd3c436e value: VM >, sharedLocks= ] >2014-07-01 06:24:56,608 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-1) [94d0a7f] START, IsVmDuringInitiatingVDSCommand( vmId = 7e1fb1c7-700f-432d-b982-06b4cd3c436e), log id: 75e4922 >2014-07-01 06:24:56,609 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-1) [94d0a7f] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 75e4922 >2014-07-01 06:24:56,621 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (ajp--127.0.0.1-8702-1) [94d0a7f] Candidate host orange-vdsd.qa.lab.tlv.redhat.com (8749c6c4-ffd0-4f2d-a877-09a832e7a8f6) was filtered out by VAR__FILTERTYPE__INTERNAL filter Network >2014-07-01 06:24:56,622 WARN [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-1) [94d0a7f] CanDoAction of action RunVm failed. Reasons:VAR__ACTION__RUN,VAR__TYPE__VM,SCHEDULING_ALL_HOSTS_FILTERED_OUT,VAR__FILTERTYPE__INTERNAL,$hostName orange-vdsd.qa.lab.tlv.redhat.com,$filterName Network,$networkNames ,VAR__DETAIL__NETWORK_MISSING,SCHEDULING_HOST_FILTERED_REASON_WITH_DETAIL >2014-07-01 06:24:56,623 INFO [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-1) [94d0a7f] Lock freed to object EngineLock [exclusiveLocks= key: 7e1fb1c7-700f-432d-b982-06b4cd3c436e value: VM >, sharedLocks= ] >2014-07-01 06:25:26,388 INFO [org.ovirt.engine.core.bll.network.vm.RemoveVmInterfaceCommand] (org.ovirt.thread.pool-8-thread-21) [25562036] Running command: RemoveVmInterfaceCommand internal: false. Entities affected : ID: 7e1fb1c7-700f-432d-b982-06b4cd3c436e Type: VM >2014-07-01 06:25:26,416 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-21) [25562036] Correlation ID: 25562036, Call Stack: null, Custom Event ID: -1, Message: Interface nic1 (VirtIO) was removed from VM td_vm1. (User: admin) >2014-07-01 06:25:30,180 INFO [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-8) [5124fcac] Lock Acquired to object EngineLock [exclusiveLocks= key: 7e1fb1c7-700f-432d-b982-06b4cd3c436e value: VM >, sharedLocks= ] >2014-07-01 06:25:30,227 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-8) [5124fcac] START, IsVmDuringInitiatingVDSCommand( vmId = 7e1fb1c7-700f-432d-b982-06b4cd3c436e), log id: 1aa1ef07 >2014-07-01 06:25:30,228 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-8) [5124fcac] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 1aa1ef07 >2014-07-01 06:25:30,237 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (ajp--127.0.0.1-8702-8) [5124fcac] Candidate host orange-vdsd.qa.lab.tlv.redhat.com (8749c6c4-ffd0-4f2d-a877-09a832e7a8f6) was filtered out by VAR__FILTERTYPE__INTERNAL filter Network >2014-07-01 06:25:30,238 WARN [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-8) [5124fcac] CanDoAction of action RunVm failed. Reasons:VAR__ACTION__RUN,VAR__TYPE__VM,SCHEDULING_ALL_HOSTS_FILTERED_OUT,VAR__FILTERTYPE__INTERNAL,$hostName orange-vdsd.qa.lab.tlv.redhat.com,$filterName Network,$networkNames ,VAR__DETAIL__NETWORK_MISSING,SCHEDULING_HOST_FILTERED_REASON_WITH_DETAIL >2014-07-01 06:25:30,239 INFO [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-8) [5124fcac] Lock freed to object EngineLock [exclusiveLocks= key: 7e1fb1c7-700f-432d-b982-06b4cd3c436e value: VM >, sharedLocks= ] >2014-07-01 06:25:46,930 INFO [org.ovirt.engine.core.bll.scheduling.commands.AddClusterPolicyCommand] (ajp--127.0.0.1-8702-4) [1fe8b1cc] Running command: AddClusterPolicyCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-01 06:25:46,945 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) [1fe8b1cc] Correlation ID: 1fe8b1cc, Call Stack: null, Custom Event ID: -1, Message: Clsuter Policy gfdfgf was added. (User: admin) >2014-07-01 06:25:58,240 INFO [org.ovirt.engine.core.bll.UpdateVdsGroupCommand] (ajp--127.0.0.1-8702-1) [64365b96] Running command: UpdateVdsGroupCommand internal: false. Entities affected : ID: 00000001-0001-0001-0001-00000000034a Type: VdsGroups >2014-07-01 06:25:58,250 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-1) [64365b96] Correlation ID: 64365b96, Call Stack: null, Custom Event ID: -1, Message: Host cluster Default was updated by admin >2014-07-01 06:26:03,451 INFO [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-11) [1ff9cb02] Lock Acquired to object EngineLock [exclusiveLocks= key: 7e1fb1c7-700f-432d-b982-06b4cd3c436e value: VM >, sharedLocks= ] >2014-07-01 06:26:03,517 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-11) [1ff9cb02] START, IsVmDuringInitiatingVDSCommand( vmId = 7e1fb1c7-700f-432d-b982-06b4cd3c436e), log id: 1fb06128 >2014-07-01 06:26:03,518 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-11) [1ff9cb02] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 1fb06128 >2014-07-01 06:26:03,546 INFO [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-17) [1ff9cb02] Running command: RunVmCommand internal: false. Entities affected : ID: 7e1fb1c7-700f-432d-b982-06b4cd3c436e Type: VM >2014-07-01 06:26:03,577 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-17) [1ff9cb02] START, UpdateVmDynamicDataVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vmDynamic=org.ovirt.engine.core.common.businessentities.VmDynamic@7081f667), log id: 374603c9 >2014-07-01 06:26:03,579 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-17) [1ff9cb02] FINISH, UpdateVmDynamicDataVDSCommand, log id: 374603c9 >2014-07-01 06:26:03,582 INFO [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-8-thread-17) [1ff9cb02] START, CreateVmVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vmId=7e1fb1c7-700f-432d-b982-06b4cd3c436e, vm=VM [td_vm1]), log id: 106ad4c7 >2014-07-01 06:26:03,682 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromCloudInitVDSCommand] (org.ovirt.thread.pool-8-thread-17) [1ff9cb02] START, CreateVmFromCloudInitVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vmId=7e1fb1c7-700f-432d-b982-06b4cd3c436e, vm=VM [td_vm1]), log id: abeaf69 >2014-07-01 06:26:03,734 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-17) [1ff9cb02] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromCloudInitVDSCommand kvmEnable=true,keyboardLayout=en-us,nice=0,displayNetwork=ovirtmgmt,copyPasteEnable=true,timeOffset=0,transparentHugePages=true,vmId=7e1fb1c7-700f-432d-b982-06b4cd3c436e,acpiEnable=true,custom={},spiceSslCipherSuite=DEFAULT,memSize=1024,smp=1,emulatedMachine=rhel6.5.0,vmType=kvm,memGuaranteedSize=1024,display=qxl,smartcardEnable=false,bootMenuEnable=false,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,smpCoresPerSocket=1,numaTune={mode=preferred},maxVCpus=160,devices=[{shared=false, iface=ide, index=3, specParams={vmPayload={file={openstack/latest/meta_data.json=ewogICJsYXVuY2hfaW5kZXgiIDogIjAiLAogICJhdmFpbGFiaWxpdHlfem9uZSIgOiAibm92YSIs >CiAgIm5ldHdvcmstaW50ZXJmYWNlcyIgOiAiYXV0b1xuIiwKICAibmV0d29ya19jb25maWciIDog >ewogICAgImNvbnRlbnRfcGF0aCIgOiAiL2NvbnRlbnQvMDAwMCIsCiAgICAicGF0aCIgOiAiL2V0 >Yy9uZXR3b3JrL2ludGVyZmFjZXMiCiAgfSwKICAidXVpZCIgOiAiZTAyOWJlY2EtZDlhMS00NDUz >LTkyZTYtNWU0NjlmZTE5MGYyIiwKICAibWV0YSIgOiB7CiAgICAiZXNzZW50aWFsIiA6ICJmYWxz >ZSIsCiAgICAicm9sZSIgOiAic2VydmVyIiwKICAgICJkc21vZGUiIDogImxvY2FsIgogIH0KfQ== >, openstack/latest/user_data=I2Nsb3VkLWNvbmZpZwpzc2hfcHdhdXRoOiB0cnVlCmRpc2FibGVfcm9vdDogMApvdXRwdXQ6CiAg >YWxsOiAnPj4gL3Zhci9sb2cvY2xvdWQtaW5pdC1vdXRwdXQubG9nJwpjaHBhc3N3ZDoKICBleHBp >cmU6IGZhbHNlCnJ1bmNtZDoKLSAnc2VkIC1pICcnL15kYXRhc291cmNlX2xpc3Q6IC9kJycgL2V0 >Yy9jbG91ZC9jbG91ZC5jZmc7IGVjaG8gJydkYXRhc291cmNlX2xpc3Q6CiAgWyJOb0Nsb3VkIiwg >IkNvbmZpZ0RyaXZlIl0nJyA+PiAvZXRjL2Nsb3VkL2Nsb3VkLmNmZycK >, openstack/content/0000=YXV0bwo= >}, volId=config-2}}, path=, device=cdrom, type=disk, readonly=true, deviceId=f630a089-9d18-48bd-94bc-4ddfa7502f23}, {specParams={vram=32768, heads=1}, device=qxl, type=video, deviceId=97b8d762-264e-40dc-ac2d-c042e8ab9b18}, {shared=false, iface=ide, index=2, specParams={path=}, path=, device=cdrom, type=disk, readonly=true, deviceId=07ef0ab3-37b6-4d39-9039-129571bb0b01}, {shared=false, volumeID=2d8aa9d2-b54e-4997-95e7-e8e21322c3ce, index=0, iface=virtio, imageID=add5a4ed-3fce-44d2-ad57-921a747069d4, domainID=86ecda08-9abd-4a3c-b13f-de0f3746ce82, specParams={}, optional=false, propagateErrors=off, device=disk, poolID=00000002-0002-0002-0002-000000000251, format=raw, type=disk, readonly=false, deviceId=add5a4ed-3fce-44d2-ad57-921a747069d4}, {index=0, model=virtio-scsi, specParams={}, device=scsi, type=controller, deviceId=f4c0650f-c33e-4f48-9f68-28eb494d7cda}],vmName=td_vm1,cpuType=Opteron_G2,fileTransferEnable=true >2014-07-01 06:26:03,908 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromCloudInitVDSCommand] (org.ovirt.thread.pool-8-thread-17) [1ff9cb02] FINISH, CreateVmFromCloudInitVDSCommand, log id: abeaf69 >2014-07-01 06:26:03,914 INFO [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-8-thread-17) [1ff9cb02] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 106ad4c7 >2014-07-01 06:26:03,915 INFO [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-17) [1ff9cb02] Lock freed to object EngineLock [exclusiveLocks= key: 7e1fb1c7-700f-432d-b982-06b4cd3c436e value: VM >, sharedLocks= ] >2014-07-01 06:26:03,927 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-17) [1ff9cb02] Correlation ID: 1ff9cb02, Job ID: 2cc48704-ffb4-416a-ad8c-9ba73962db49, Call Stack: null, Custom Event ID: -1, Message: VM td_vm1 was started by admin (Host: orange-vdsd.qa.lab.tlv.redhat.com). >2014-07-01 06:26:05,897 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-29) VM td_vm1 7e1fb1c7-700f-432d-b982-06b4cd3c436e moved from WaitForLaunch --> PoweringUp >2014-07-01 06:26:05,902 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-29) START, FullListVdsCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vds=Host[orange-vdsd.qa.lab.tlv.redhat.com,8749c6c4-ffd0-4f2d-a877-09a832e7a8f6], vmIds=[7e1fb1c7-700f-432d-b982-06b4cd3c436e]), log id: 6c4c35d0 >2014-07-01 06:26:06,024 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-29) FINISH, FullListVdsCommand, return: [{displaySecurePort=5901, kvmEnable=true, nicModel=rtl8139,pv, keyboardLayout=en-us, displayIp=10.35.128.18, pauseCode=NOERR, nice=0, displayNetwork=ovirtmgmt, copyPasteEnable=true, timeOffset=0, transparentHugePages=true, vmId=7e1fb1c7-700f-432d-b982-06b4cd3c436e, acpiEnable=true, custom={}, spiceSslCipherSuite=DEFAULT, memSize=1024, smp=1, displayPort=5900, emulatedMachine=rhel6.5.0, vmType=kvm, status=Up, memGuaranteedSize=1024, display=qxl, pid=22208, smartcardEnable=false, bootMenuEnable=false, spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard, numaTune={mode=preferred}, smpCoresPerSocket=1, maxVCpus=160, clientIp=, devices=[Ljava.lang.Object;@7f61223b, vmName=td_vm1, fileTransferEnable=true, cpuType=Opteron_G2}], log id: 6c4c35d0 >2014-07-01 06:26:06,027 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-29) Received a memballoon Device without an address when processing VM 7e1fb1c7-700f-432d-b982-06b4cd3c436e devices, skipping device: {alias=balloon0, specParams={model=none}, device=memballoon, type=balloon} >2014-07-01 06:26:06,028 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-29) Received a spice Device without an address when processing VM 7e1fb1c7-700f-432d-b982-06b4cd3c436e devices, skipping device: {port=5900, specParams={spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard, keyMap=en-us, displayNetwork=ovirtmgmt, copyPasteEnable=true, displayIp=10.35.128.18}, device=spice, tlsPort=5901, type=graphics} >2014-07-01 06:26:11,856 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-37) [219d6efc] Running command: ShutdownVmCommand internal: false. Entities affected : ID: 7e1fb1c7-700f-432d-b982-06b4cd3c436e Type: VM >2014-07-01 06:26:11,858 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-37) [219d6efc] Entered (VM td_vm1). >2014-07-01 06:26:11,858 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-37) [219d6efc] Cannot shutdown VM td_vm1, status is not up. Stopping instead. >2014-07-01 06:26:11,868 INFO [org.ovirt.engine.core.bll.StopVmCommand] (org.ovirt.thread.pool-8-thread-37) [1db42ee9] Running command: StopVmCommand internal: true. Entities affected : ID: 7e1fb1c7-700f-432d-b982-06b4cd3c436e Type: VM >2014-07-01 06:26:11,872 INFO [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-37) [1db42ee9] START, DestroyVmVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vmId=7e1fb1c7-700f-432d-b982-06b4cd3c436e, force=false, secondsToWait=0, gracefully=false, reason=), log id: 737b1b39 >2014-07-01 06:26:11,881 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-37) [1db42ee9] START, DestroyVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, vmId=7e1fb1c7-700f-432d-b982-06b4cd3c436e, force=false, secondsToWait=0, gracefully=false, reason=), log id: 38a46c1f >2014-07-01 06:26:12,441 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-37) [1db42ee9] FINISH, DestroyVDSCommand, log id: 38a46c1f >2014-07-01 06:26:12,452 INFO [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-37) [1db42ee9] FINISH, DestroyVmVDSCommand, return: Down, log id: 737b1b39 >2014-07-01 06:26:12,458 INFO [org.ovirt.engine.core.bll.ProcessDownVmCommand] (org.ovirt.thread.pool-8-thread-28) [5f7ee1a] Running command: ProcessDownVmCommand internal: true. >2014-07-01 06:26:12,473 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-37) [1db42ee9] Correlation ID: 1db42ee9, Job ID: 01e852c1-3071-415e-ae15-f048a045b91f, Call Stack: null, Custom Event ID: -1, Message: VM td_vm1 was powered off ungracefully by admin (Host: orange-vdsd.qa.lab.tlv.redhat.com) (Reason: Not Specified). >2014-07-01 06:26:35,476 INFO [org.ovirt.engine.core.bll.UpdateVdsGroupCommand] (ajp--127.0.0.1-8702-3) [236aa083] Running command: UpdateVdsGroupCommand internal: false. Entities affected : ID: 00000001-0001-0001-0001-00000000034a Type: VdsGroups >2014-07-01 06:26:35,490 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [236aa083] Correlation ID: 236aa083, Call Stack: null, Custom Event ID: -1, Message: Host cluster Default was updated by admin >2014-07-01 06:36:25,912 INFO [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-1) [1c97ffe2] Lock Acquired to object EngineLock [exclusiveLocks= key: 7e1fb1c7-700f-432d-b982-06b4cd3c436e value: VM >, sharedLocks= ] >2014-07-01 06:36:25,932 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-1) [1c97ffe2] START, IsVmDuringInitiatingVDSCommand( vmId = 7e1fb1c7-700f-432d-b982-06b4cd3c436e), log id: 15210d9a >2014-07-01 06:36:25,932 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-1) [1c97ffe2] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 15210d9a >2014-07-01 06:36:25,941 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (ajp--127.0.0.1-8702-1) [1c97ffe2] Candidate host orange-vdsd.qa.lab.tlv.redhat.com (8749c6c4-ffd0-4f2d-a877-09a832e7a8f6) was filtered out by VAR__FILTERTYPE__INTERNAL filter Network >2014-07-01 06:36:25,942 WARN [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-1) [1c97ffe2] CanDoAction of action RunVm failed. Reasons:VAR__ACTION__RUN,VAR__TYPE__VM,SCHEDULING_ALL_HOSTS_FILTERED_OUT,VAR__FILTERTYPE__INTERNAL,$hostName orange-vdsd.qa.lab.tlv.redhat.com,$filterName Network,$networkNames ,VAR__DETAIL__NETWORK_MISSING,SCHEDULING_HOST_FILTERED_REASON_WITH_DETAIL >2014-07-01 06:36:25,943 INFO [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-1) [1c97ffe2] Lock freed to object EngineLock [exclusiveLocks= key: 7e1fb1c7-700f-432d-b982-06b4cd3c436e value: VM >, sharedLocks= ] >2014-07-01 06:39:36,283 INFO [org.ovirt.engine.core.bll.network.dc.UpdateNetworkCommand] (ajp--127.0.0.1-8702-5) [746d9c96] Running command: UpdateNetworkCommand internal: false. Entities affected : ID: 66367911-a0d0-4187-b46d-13c3bc065348 Type: Network >2014-07-01 06:39:36,313 INFO [org.ovirt.engine.core.bll.network.host.PersistentSetupNetworksCommand] (org.ovirt.thread.pool-8-thread-44) [5b512818] Running command: PersistentSetupNetworksCommand internal: true. Entities affected : ID: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 Type: VDS >2014-07-01 06:39:36,318 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-5) [5b512818] Correlation ID: 746d9c96, Call Stack: null, Custom Event ID: -1, Message: Network vm_net was updated on Data Center: Default >2014-07-01 06:39:36,318 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-44) [5b512818] Correlation ID: 5b512818, Call Stack: null, Custom Event ID: -1, Message: (1/1): Applying changes for network(s) vm_net on host orange-vdsd.qa.lab.tlv.redhat.com. (User: admin) >2014-07-01 06:39:36,339 INFO [org.ovirt.engine.core.bll.network.host.SetupNetworksCommand] (org.ovirt.thread.pool-8-thread-44) [5b512818] Running command: SetupNetworksCommand internal: true. Entities affected : ID: 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6 Type: VDS >2014-07-01 06:39:36,346 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (org.ovirt.thread.pool-8-thread-44) [5b512818] START, SetupNetworksVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, force=false, checkConnectivity=true, conectivityTimeout=120, > networks=[vm_net {id=66367911-a0d0-4187-b46d-13c3bc065348, description=null, comment=null, subnet=null, gateway=null, type=null, vlanId=null, stp=false, dataCenterId=00000002-0002-0002-0002-000000000251, mtu=0, vmNetwork=true, cluster=NetworkCluster {id={clusterId=null, networkId=null}, status=OPERATIONAL, display=false, required=true, migration=false}, providedBy=null, label=null, qosId=null}], > bonds=[], > interfaces=[eth0 {id=44c228c6-2c2a-4f7f-899a-ab4ce9231100, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth0, macAddress=00:16:36:76:3e:ab, networkName=ovirtmgmt, bondName=null, bootProtocol=NONE, address=10.35.128.18, subnet=255.255.255.0, gateway=10.35.128.254, mtu=1500, bridged=true, speed=0, type=2, networkImplementationDetails=null}, > eth2 {id=0a752445-4430-4e6d-b51d-aa0139234261, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth2, macAddress=00:16:36:76:3e:a9, networkName=vm_nett, bondName=null, bootProtocol=NONE, address=, subnet=, gateway=, mtu=1500, bridged=true, speed=0, type=0, networkImplementationDetails=null}, > eth1 {id=34ede921-933f-4667-9931-9aa3988443c0, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth1, macAddress=00:16:36:76:3e:ac, networkName=null, bondName=null, bootProtocol=NONE, address=, subnet=, gateway=null, mtu=1500, bridged=false, speed=0, type=0, networkImplementationDetails=null}, > eth3 {id=4c415151-0484-4030-9d12-14bde9ba1e47, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth3, macAddress=00:16:36:76:3e:aa, networkName=null, bondName=null, bootProtocol=NONE, address=, subnet=, gateway=null, mtu=1500, bridged=false, speed=1000, type=0, networkImplementationDetails=null}, > eth1.0 {id=6426d1b2-57a1-4af3-8111-3cf4944e17a2, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth1.0, networkName=vm_net, vlanId=null, bootProtocol=NONE, address=, subnet=, gateway=, mtu=1500, bridged=true, type=0, networkImplementationDetails=null}], > removedNetworks=[], > removedBonds=[]), log id: 5cca424b >2014-07-01 06:39:36,369 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (org.ovirt.thread.pool-8-thread-44) [5b512818] FINISH, SetupNetworksVDSCommand, log id: 5cca424b >2014-07-01 06:39:38,961 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (org.ovirt.thread.pool-8-thread-44) [5b512818] Failed in SetupNetworksVDS method >2014-07-01 06:39:38,962 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (org.ovirt.thread.pool-8-thread-44) [5b512818] org.ovirt.engine.core.vdsbroker.vdsbroker.VDSErrorException: VDSGenericException: VDSErrorException: Failed to SetupNetworksVDS, error = unknown nic: eth1.0, code = 23 >2014-07-01 06:39:38,963 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (org.ovirt.thread.pool-8-thread-44) [5b512818] Command SetupNetworksVDSCommand(HostName = orange-vdsd.qa.lab.tlv.redhat.com, HostId = 8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, force=false, checkConnectivity=true, conectivityTimeout=120, > networks=[vm_net {id=66367911-a0d0-4187-b46d-13c3bc065348, description=null, comment=null, subnet=null, gateway=null, type=null, vlanId=null, stp=false, dataCenterId=00000002-0002-0002-0002-000000000251, mtu=0, vmNetwork=true, cluster=NetworkCluster {id={clusterId=null, networkId=null}, status=OPERATIONAL, display=false, required=true, migration=false}, providedBy=null, label=null, qosId=null}], > bonds=[], > interfaces=[eth0 {id=44c228c6-2c2a-4f7f-899a-ab4ce9231100, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth0, macAddress=00:16:36:76:3e:ab, networkName=ovirtmgmt, bondName=null, bootProtocol=NONE, address=10.35.128.18, subnet=255.255.255.0, gateway=10.35.128.254, mtu=1500, bridged=true, speed=0, type=2, networkImplementationDetails=null}, > eth2 {id=0a752445-4430-4e6d-b51d-aa0139234261, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth2, macAddress=00:16:36:76:3e:a9, networkName=vm_nett, bondName=null, bootProtocol=NONE, address=, subnet=, gateway=, mtu=1500, bridged=true, speed=0, type=0, networkImplementationDetails=null}, > eth1 {id=34ede921-933f-4667-9931-9aa3988443c0, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth1, macAddress=00:16:36:76:3e:ac, networkName=null, bondName=null, bootProtocol=NONE, address=, subnet=, gateway=null, mtu=1500, bridged=false, speed=0, type=0, networkImplementationDetails=null}, > eth3 {id=4c415151-0484-4030-9d12-14bde9ba1e47, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth3, macAddress=00:16:36:76:3e:aa, networkName=null, bondName=null, bootProtocol=NONE, address=, subnet=, gateway=null, mtu=1500, bridged=false, speed=1000, type=0, networkImplementationDetails=null}, > eth1.0 {id=6426d1b2-57a1-4af3-8111-3cf4944e17a2, vdsId=8749c6c4-ffd0-4f2d-a877-09a832e7a8f6, name=eth1.0, networkName=vm_net, vlanId=null, bootProtocol=NONE, address=, subnet=, gateway=, mtu=1500, bridged=true, type=0, networkImplementationDetails=null}], > removedNetworks=[], > removedBonds=[]) execution failed. Exception: VDSErrorException: VDSGenericException: VDSErrorException: Failed to SetupNetworksVDS, error = unknown nic: eth1.0, code = 23 >2014-07-01 06:39:38,967 ERROR [org.ovirt.engine.core.bll.network.host.SetupNetworksCommand] (org.ovirt.thread.pool-8-thread-44) [5b512818] Command org.ovirt.engine.core.bll.network.host.SetupNetworksCommand throw Vdc Bll exception. With error message VdcBLLException: org.ovirt.engine.core.vdsbroker.vdsbroker.VDSErrorException: VDSGenericException: VDSErrorException: Failed to SetupNetworksVDS, error = unknown nic: eth1.0, code = 23 (Failed with error ERR_BAD_NIC and code 23) >2014-07-01 06:39:38,973 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-44) [5b512818] Correlation ID: 5b512818, Call Stack: null, Custom Event ID: -1, Message: (1/1): Failed to apply changes for network(s) vm_net on host orange-vdsd.qa.lab.tlv.redhat.com. (User: admin)
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=913728">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 1114987
: 913728 |
917122