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 925487 Details for
Bug 1128417
Suspend VM doesn't work
[?]
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), 172.93 KB, created by
Vered Volansky
on 2014-08-10 11:01:34 UTC
(
hide
)
Description:
engine.log
Filename:
MIME Type:
Creator:
Vered Volansky
Created:
2014-08-10 11:01:34 UTC
Size:
172.93 KB
patch
obsolete
>2014-08-10 13:14:38,262 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Loaded file "/home/vvolansk/ovirt-engine/share/ovirt-engine/services/ovirt-engine/ovirt-engine.conf". >2014-08-10 13:14:38,263 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) The file "/home/vvolansk/ovirt-engine/etc/ovirt-engine/engine.conf" doesn't exist or isn't readable. Will return an empty set of properties. >2014-08-10 13:14:38,264 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Loaded file "/home/vvolansk/ovirt-engine/etc/ovirt-engine/engine.conf.d/10-setup-database.conf". >2014-08-10 13:14:38,265 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Loaded file "/home/vvolansk/ovirt-engine/etc/ovirt-engine/engine.conf.d/10-setup-jboss.conf". >2014-08-10 13:14:38,266 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Loaded file "/home/vvolansk/ovirt-engine/etc/ovirt-engine/engine.conf.d/10-setup-pki.conf". >2014-08-10 13:14:38,267 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Loaded file "/home/vvolansk/ovirt-engine/etc/ovirt-engine/engine.conf.d/10-setup-protocols.conf". >2014-08-10 13:14:38,268 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_AJP_ENABLED" is "false". >2014-08-10 13:14:38,269 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_AJP_PORT" is "None". >2014-08-10 13:14:38,269 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_APPS" is "engine.ear restapi.war legacy_restapi.war". >2014-08-10 13:14:38,270 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_CACHE" is "/home/vvolansk/ovirt-engine/var/cache/ovirt-engine". >2014-08-10 13:14:38,270 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_CHECK_INTERVAL" is "1000". >2014-08-10 13:14:38,271 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_CONNECTION_TIMEOUT" is "300000". >2014-08-10 13:14:38,272 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_DATABASE" is "engine". >2014-08-10 13:14:38,272 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_DRIVER" is "org.postgresql.Driver". >2014-08-10 13:14:38,273 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_HOST" is "localhost". >2014-08-10 13:14:38,273 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_MAX_CONNECTIONS" is "100". >2014-08-10 13:14:38,274 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_MIN_CONNECTIONS" is "1". >2014-08-10 13:14:38,274 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_PASSWORD" is "***". >2014-08-10 13:14:38,275 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_PORT" is "5432". >2014-08-10 13:14:38,275 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_SECURED" is "False". >2014-08-10 13:14:38,276 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_SECURED_VALIDATION" is "False". >2014-08-10 13:14:38,276 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_URL" is "jdbc:postgresql://localhost:5432/engine?sslfactory=org.postgresql.ssl.NonValidatingFactory". >2014-08-10 13:14:38,277 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_USER" is "engine". >2014-08-10 13:14:38,278 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DEBUG_ADDRESS" is "127.0.0.1:8787". >2014-08-10 13:14:38,278 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DOC" is "/home/vvolansk/ovirt-engine/share/doc/ovirt-engine". >2014-08-10 13:14:38,279 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_ETC" is "/home/vvolansk/ovirt-engine/etc/ovirt-engine". >2014-08-10 13:14:38,280 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_EXTENSION_PATH" is "/home/vvolansk/ovirt-engine/share/ovirt-engine/extensions.d:/home/vvolansk/ovirt-engine/etc/ovirt-engine/extensions.d". >2014-08-10 13:14:38,281 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_FQDN" is "vered.laptop.tlv.redhat.com". >2014-08-10 13:14:38,281 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_GROUP" is "ovirt". >2014-08-10 13:14:38,282 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_HEAP_MAX" is "1g". >2014-08-10 13:14:38,282 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_HEAP_MIN" is "1g". >2014-08-10 13:14:38,283 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_HTTPS_ENABLED" is "true". >2014-08-10 13:14:38,283 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_HTTPS_PORT" is "8443". >2014-08-10 13:14:38,284 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_HTTPS_PROTOCOLS" is "SSLv3,TLSv1,TLSv1.1,TLSv1.2". >2014-08-10 13:14:38,284 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_HTTP_ENABLED" is "true". >2014-08-10 13:14:38,285 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_HTTP_PORT" is "8080". >2014-08-10 13:14:38,285 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_JAVA_MODULEPATH" is "/home/vvolansk/ovirt-engine/share/ovirt-engine/modules". >2014-08-10 13:14:38,289 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_JVM_ARGS" is " -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath="/home/vvolansk/ovirt-engine/var/log/ovirt-engine/dump"". >2014-08-10 13:14:38,290 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_LOG" is "/home/vvolansk/ovirt-engine/var/log/ovirt-engine". >2014-08-10 13:14:38,291 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_LOG_TO_CONSOLE" is "true". >2014-08-10 13:14:38,291 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_MANUAL" is "/home/vvolansk/ovirt-engine/share/ovirt-engine/manual". >2014-08-10 13:14:38,292 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PERM_MAX" is "256m". >2014-08-10 13:14:38,292 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PERM_MIN" is "256m". >2014-08-10 13:14:38,293 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI" is "/home/vvolansk/ovirt-engine/etc/pki/ovirt-engine". >2014-08-10 13:14:38,293 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI_CA" is "/home/vvolansk/ovirt-engine/etc/pki/ovirt-engine/ca.pem". >2014-08-10 13:14:38,295 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI_ENGINE_CERT" is "/home/vvolansk/ovirt-engine/etc/pki/ovirt-engine/certs/engine.cer". >2014-08-10 13:14:38,296 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI_ENGINE_STORE" is "/home/vvolansk/ovirt-engine/etc/pki/ovirt-engine/keys/engine.p12". >2014-08-10 13:14:38,297 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI_ENGINE_STORE_ALIAS" is "1". >2014-08-10 13:14:38,301 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI_ENGINE_STORE_PASSWORD" is "***". >2014-08-10 13:14:38,302 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI_ENGINE_STORE_TYPE" is "PKCS12". >2014-08-10 13:14:38,302 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI_TRUST_STORE" is "/home/vvolansk/ovirt-engine/etc/pki/ovirt-engine/.truststore". >2014-08-10 13:14:38,303 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI_TRUST_STORE_PASSWORD" is "***". >2014-08-10 13:14:38,303 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI_TRUST_STORE_TYPE" is "JKS". >2014-08-10 13:14:38,304 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PROPERTIES" is " jsse.enableSNIExtension=false "java.security.krb5.conf=/home/vvolansk/ovirt-engine/etc/ovirt-engine/krb5.conf"". >2014-08-10 13:14:38,311 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PROXY_ENABLED" is "false". >2014-08-10 13:14:38,312 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PROXY_HTTPS_PORT" is "443". >2014-08-10 13:14:38,312 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PROXY_HTTP_PORT" is "80". >2014-08-10 13:14:38,313 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_REPORTS_UI" is "/home/vvolansk/ovirt-engine/var/lib/ovirt-engine/reports.xml". >2014-08-10 13:14:38,313 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_STOP_INTERVAL" is "1". >2014-08-10 13:14:38,314 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_STOP_TIME" is "10". >2014-08-10 13:14:38,314 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_TMP" is "/home/vvolansk/ovirt-engine/var/tmp/ovirt-engine". >2014-08-10 13:14:38,315 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_UP_MARK" is "/home/vvolansk/ovirt-engine/var/lib/ovirt-engine/engine.up". >2014-08-10 13:14:38,316 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_URI" is "/ovirt-engine". >2014-08-10 13:14:38,316 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_USER" is "ovirt". >2014-08-10 13:14:38,316 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_USR" is "/home/vvolansk/ovirt-engine/share/ovirt-engine". >2014-08-10 13:14:38,317 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_VAR" is "/home/vvolansk/ovirt-engine/var/lib/ovirt-engine". >2014-08-10 13:14:38,318 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_VERBOSE_GC" is "false". >2014-08-10 13:14:38,318 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "JBOSS_HOME" is "/usr/share/jboss-as". >2014-08-10 13:14:38,318 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "JBOSS_RUNTIME" is "/home/vvolansk/ovirt-engine/var/lib/ovirt-engine/jboss_runtime". >2014-08-10 13:14:38,319 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "SENSITIVE_KEYS" is ",ENGINE_DB_PASSWORD,ENGINE_PKI_TRUST_STORE_PASSWORD,ENGINE_PKI_ENGINE_STORE_PASSWORD". >2014-08-10 13:14:38,654 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) Start initializing Backend >2014-08-10 13:14:38,751 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-1) Failed to decrypt value for property AdminPassword will be used encrypted value: javax.crypto.BadPaddingException: Data must start with zero > at sun.security.rsa.RSAPadding.unpadV15(RSAPadding.java:325) [rt.jar:1.7.0_25] > at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:272) [rt.jar:1.7.0_25] > at com.sun.crypto.provider.RSACipher.doFinal(RSACipher.java:356) > at com.sun.crypto.provider.RSACipher.engineDoFinal(RSACipher.java:382) > at javax.crypto.Cipher.doFinal(Cipher.java:1922) [jce.jar:1.7.0_25] > at org.ovirt.engine.core.utils.crypt.EngineEncryptionUtils.decrypt(EngineEncryptionUtils.java:163) [utils.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.getValue(DBConfigUtils.java:112) [dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.updateOption(DBConfigUtils.java:263) [dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.refreshVdcOptionCache(DBConfigUtils.java:38) [dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.<init>(DBConfigUtils.java:171) [dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.<init>(DBConfigUtils.java:157) [dal.jar:] > at org.ovirt.engine.core.bll.Backend.initialize(Backend.java:187) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.create(Backend.java:138) [bll.jar:] > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_25] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_25] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_25] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_25] > at org.jboss.as.ee.component.ManagedReferenceLifecycleMethodInterceptorFactory$ManagedReferenceLifecycleMethodInterceptor.processInvocation(ManagedReferenceLifecycleMethodInterceptorFactory.java:130) [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.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.weld.injection.WeldInjectionInterceptor.processInvocation(WeldInjectionInterceptor.java:73) [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.as.ee.component.ManagedReferenceInterceptorFactory$ManagedReferenceInterceptor.processInvocation(ManagedReferenceInterceptorFactory.java:95) [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.ManagedReferenceInterceptorFactory$ManagedReferenceInterceptor.processInvocation(ManagedReferenceInterceptorFactory.java:95) [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.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.ejb3.tx.CMTTxInterceptor.invokeInOurTx(CMTTxInterceptor.java:228) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.requiresNew(CMTTxInterceptor.java:333) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.SingletonLifecycleCMTTxInterceptor.processInvocation(SingletonLifecycleCMTTxInterceptor.java:56) [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.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.BasicComponent.constructComponentInstance(BasicComponent.java:161) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.BasicComponent.createInstance(BasicComponent.java:85) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.component.singleton.SingletonComponent.getComponentInstance(SingletonComponent.java:116) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.component.singleton.SingletonComponent.start(SingletonComponent.java:130) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.ComponentStartService.start(ComponentStartService.java:44) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1811) > at org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1746) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_25] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_25] > at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25] > >2014-08-10 13:14:38,828 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-1) Failed to decrypt value for property LocalAdminPassword will be used encrypted value: javax.crypto.BadPaddingException: Data must start with zero > at sun.security.rsa.RSAPadding.unpadV15(RSAPadding.java:325) [rt.jar:1.7.0_25] > at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:272) [rt.jar:1.7.0_25] > at com.sun.crypto.provider.RSACipher.doFinal(RSACipher.java:356) > at com.sun.crypto.provider.RSACipher.engineDoFinal(RSACipher.java:382) > at javax.crypto.Cipher.doFinal(Cipher.java:1922) [jce.jar:1.7.0_25] > at org.ovirt.engine.core.utils.crypt.EngineEncryptionUtils.decrypt(EngineEncryptionUtils.java:163) [utils.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.getValue(DBConfigUtils.java:112) [dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.updateOption(DBConfigUtils.java:263) [dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.refreshVdcOptionCache(DBConfigUtils.java:38) [dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.<init>(DBConfigUtils.java:171) [dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.<init>(DBConfigUtils.java:157) [dal.jar:] > at org.ovirt.engine.core.bll.Backend.initialize(Backend.java:187) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.create(Backend.java:138) [bll.jar:] > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_25] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_25] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_25] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_25] > at org.jboss.as.ee.component.ManagedReferenceLifecycleMethodInterceptorFactory$ManagedReferenceLifecycleMethodInterceptor.processInvocation(ManagedReferenceLifecycleMethodInterceptorFactory.java:130) [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.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.weld.injection.WeldInjectionInterceptor.processInvocation(WeldInjectionInterceptor.java:73) [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.as.ee.component.ManagedReferenceInterceptorFactory$ManagedReferenceInterceptor.processInvocation(ManagedReferenceInterceptorFactory.java:95) [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.ManagedReferenceInterceptorFactory$ManagedReferenceInterceptor.processInvocation(ManagedReferenceInterceptorFactory.java:95) [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.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.ejb3.tx.CMTTxInterceptor.invokeInOurTx(CMTTxInterceptor.java:228) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.requiresNew(CMTTxInterceptor.java:333) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.SingletonLifecycleCMTTxInterceptor.processInvocation(SingletonLifecycleCMTTxInterceptor.java:56) [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.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.BasicComponent.constructComponentInstance(BasicComponent.java:161) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.BasicComponent.createInstance(BasicComponent.java:85) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.component.singleton.SingletonComponent.getComponentInstance(SingletonComponent.java:116) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.component.singleton.SingletonComponent.start(SingletonComponent.java:130) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.ComponentStartService.start(ComponentStartService.java:44) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1811) > at org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1746) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_25] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_25] > at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25] > >2014-08-10 13:14:38,859 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-1) Could not parse option AutoRecoveryAllowedTypes value. >2014-08-10 13:14:38,869 INFO [org.ovirt.engine.core.utils.osinfo.OsInfoPreferencesLoader] (MSC service thread 1-1) Loaded file /home/vvolansk/ovirt-engine/etc/ovirt-engine/osinfo.conf.d/00-defaults.properties >2014-08-10 13:14:39,000 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) Running ovirt-engine 3.5.0.0 >2014-08-10 13:14:39,001 INFO [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-1) Start initializing dictionaries >2014-08-10 13:14:39,004 INFO [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-1) Finished initializing dictionaries >2014-08-10 13:14:39,004 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Start initializing AuditLogCleanupManager >2014-08-10 13:14:39,005 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Setting audit cleanup manager to run at: 35 35 3 * * ? >2014-08-10 13:14:39,021 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Finished initializing AuditLogCleanupManager >2014-08-10 13:14:39,021 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Start initializing CommandEntityCleanupManager >2014-08-10 13:14:39,022 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Setting command entity cleanup manager to run at: 35 35 3 * * ? >2014-08-10 13:14:39,023 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Finished initializing CommandEntityCleanupManager >2014-08-10 13:14:39,025 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-1) Start initializing TagsDirector >2014-08-10 13:14:39,036 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-1) Tag root added to tree >2014-08-10 13:14:39,045 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-1) Finished initializing TagsDirector >2014-08-10 13:14:39,046 INFO [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-1) Start initializing IsoDomainListSyncronizer >2014-08-10 13:14:39,053 INFO [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-1) Finished initializing IsoDomainListSyncronizer >2014-08-10 13:14:39,112 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) Completed initializing handlers >2014-08-10 13:14:39,132 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-1) Start initializing ErrorTranslatorImpl >2014-08-10 13:14:39,141 WARN [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-1) Code MAC_ADDRESS_IS_IN_USE appears more than once in string table. >2014-08-10 13:14:39,143 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-1) Finished initializing ErrorTranslatorImpl >2014-08-10 13:14:39,143 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-1) Start initializing ErrorTranslatorImpl >2014-08-10 13:14:39,144 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-1) Finished initializing ErrorTranslatorImpl >2014-08-10 13:14:39,145 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) Mark incomplete jobs as UNKNOWN >2014-08-10 13:14:39,192 INFO [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-1) Start initializing JobRepositoryCleanupManager >2014-08-10 13:14:39,193 INFO [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-1) Finished initializing JobRepositoryCleanupManager >2014-08-10 13:14:39,193 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-1) Start initializing AutoRecoveryManager >2014-08-10 13:14:39,196 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-1) Finished initializing AutoRecoveryManager >2014-08-10 13:14:39,198 INFO [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-1) Start initializing ExecutionMessageDirector >2014-08-10 13:14:39,202 INFO [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-1) Finished initializing ExecutionMessageDirector >2014-08-10 13:14:39,251 INFO [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-1) Start of enabled extensions list >2014-08-10 13:14:39,251 INFO [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-1) 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-08-10 13:14:39,252 INFO [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-1) 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-08-10 13:14:39,253 INFO [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-1) End of enabled extensions list >2014-08-10 13:14:39,322 INFO [org.ovirt.engine.core.bll.aaa.DbUserCacheManager] (MSC service thread 1-8) Start initializing DbUserCacheManager >2014-08-10 13:14:39,324 INFO [org.ovirt.engine.core.bll.aaa.DbUserCacheManager] (MSC service thread 1-8) Finished initializing DbUserCacheManager >2014-08-10 13:14:39,332 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (MSC service thread 1-8) Initialization of AsyncTaskManager completed successfully. >2014-08-10 13:14:39,334 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-8) Start initializing ResourceManager >2014-08-10 13:14:39,415 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-8) Entered VdsManager constructor >2014-08-10 13:14:39,441 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-8) Initialize vdsBroker (battlestar.tlv.redhat.com,54,321) >2014-08-10 13:14:39,583 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-8) VDS 9c67d903-8424-4cda-8bb1-3d8652b92d77 was added to the Resource Manager >2014-08-10 13:14:39,604 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-8) Finished initializing ResourceManager >2014-08-10 13:14:39,608 INFO [org.ovirt.engine.core.bll.OvfDataUpdater] (MSC service thread 1-8) Initialization of OvfDataUpdater completed successfully. >2014-08-10 13:14:39,609 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-8) Start scheduling to enable vds load balancer >2014-08-10 13:14:39,612 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-8) Finished scheduling to enable vds load balancer >2014-08-10 13:14:39,612 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-8) Start HA Reservation check >2014-08-10 13:14:39,614 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-8) Finished HA Reservation check >2014-08-10 13:14:39,630 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-8) Init VM custom properties utilities >2014-08-10 13:14:39,635 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-8) Init device custom properties utilities >2014-08-10 13:14:39,639 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-8) Initializing Scheduling manager >2014-08-10 13:14:39,689 INFO [org.ovirt.engine.core.bll.network.macpoolmanager.MacPoolManagerRanges] (org.ovirt.thread.pool-8-thread-1) Start initializing MacPoolManagerRanges >2014-08-10 13:14:39,696 INFO [org.ovirt.engine.core.bll.network.macpoolmanager.MacPoolManagerRanges] (org.ovirt.thread.pool-8-thread-1) Finished initializing. Available MACs in pool: 150 >2014-08-10 13:14:39,697 INFO [org.ovirt.engine.core.bll.network.macpoolmanager.MacPoolPerDc] (org.ovirt.thread.pool-8-thread-1) successfully initialized >2014-08-10 13:14:39,705 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-8) External scheduler disabled, discovery skipped >2014-08-10 13:14:39,706 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-8) Initialized Scheduling manager >2014-08-10 13:14:39,706 INFO [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-8) Initializing DWH Heart Beat >2014-08-10 13:14:39,710 INFO [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-8) DWH Heart Beat initialized >2014-08-10 13:14:39,768 INFO [org.ovirt.engine.core.bll.aaa.SyncUsers] (DefaultQuartzScheduler_Worker-1) Principal admin::internal synchronized >2014-08-10 13:14:43,324 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-6) START, GetHardwareInfoVDSCommand(HostName = h1, HostId = 9c67d903-8424-4cda-8bb1-3d8652b92d77, vds=Host[h1,9c67d903-8424-4cda-8bb1-3d8652b92d77]), log id: 73dea836 >2014-08-10 13:14:43,331 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-6) FINISH, GetHardwareInfoVDSCommand, log id: 73dea836 >2014-08-10 13:14:43,348 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) 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-08-10 13:14:43,357 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: UNASSIGNED not exist in string table >2014-08-10 13:14:43,357 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: VDS_AUTO_FENCE_STATUS not exist in string table >2014-08-10 13:14:43,358 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: VDS_AUTO_FENCE_STATUS_FAILED not exist in string table >2014-08-10 13:14:43,359 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: VDS_AUTO_FENCE_FAILED_CALL_FENCE_SPM not exist in string table >2014-08-10 13:14:43,359 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: VDS_HIGH_NETWORK_USE not exist in string table >2014-08-10 13:14:43,361 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: VDS_NOT_RESTARTED_DUE_TO_POLICY not exist in string table >2014-08-10 13:14:43,361 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_FAILED_REMOVE_VM not exist in string table >2014-08-10 13:14:43,362 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_RUN_UNLOCK_ENTITY_SCRIPT not exist in string table >2014-08-10 13:14:43,363 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: VDS_NETWORK_MTU_DIFFER_FROM_LOGICAL_NETWORK not exist in string table >2014-08-10 13:14:43,368 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: VDS_HOST_IN_CONNECTING_STATE not exist in string table >2014-08-10 13:14:43,369 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: STORAGE_ACTIVATE_ASYNC not exist in string table >2014-08-10 13:14:43,370 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: DWH_STOPPED not exist in string table >2014-08-10 13:14:43,371 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: DWH_STARTED not exist in string table >2014-08-10 13:14:43,371 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: DWH_ERROR not exist in string table >2014-08-10 13:14:43,380 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_REMOVE_AUDIT_LOG not exist in string table >2014-08-10 13:14:43,380 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_REMOVE_AUDIT_LOG_FAILED not exist in string table >2014-08-10 13:14:43,381 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_CLEAR_ALL_DISMISSED_AUDIT_LOG not exist in string table >2014-08-10 13:14:43,381 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_CLEAR_ALL_DISMISSED_AUDIT_LOG_FAILED not exist in string table >2014-08-10 13:14:43,548 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Host h1 does not enforce SELinux. >2014-08-10 13:14:43,549 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-6) Host h1 is running with disabled SELinux. >2014-08-10 13:14:43,823 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-6) Initializing Host: h1 >2014-08-10 13:14:43,898 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (DefaultQuartzScheduler_Worker-6) [5c4b5f39] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: 9c67d903-8424-4cda-8bb1-3d8652b92d77 Type: VDS >2014-08-10 13:14:49,652 INFO [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (DefaultQuartzScheduler_Worker-20) [6efa8e9f] Running command: SetStoragePoolStatusCommand internal: true. Entities affected : ID: 00000002-0002-0002-0002-00000000037f Type: StoragePool >2014-08-10 13:14:49,668 INFO [org.ovirt.engine.core.vdsbroker.storage.StoragePoolDomainHelper] (DefaultQuartzScheduler_Worker-20) [6efa8e9f] Storage Pool 00000002-0002-0002-0002-00000000037f - Updating Storage Domain d54e6e6c-81f2-41f2-90ab-7e3c2457938a status from Active to Unknown, reason : null >2014-08-10 13:14:49,676 INFO [org.ovirt.engine.core.vdsbroker.storage.StoragePoolDomainHelper] (DefaultQuartzScheduler_Worker-20) [6efa8e9f] Storage Pool 00000002-0002-0002-0002-00000000037f - Updating Storage Domain 7158eae9-4886-4af7-af0b-d668ecee9e4f status from Active to Unknown, reason : null >2014-08-10 13:14:49,743 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-20) [6efa8e9f] Correlation ID: 6efa8e9f, Call Stack: null, Custom Event ID: -1, Message: Invalid status on Data Center Default. Setting status to Non Responsive. >2014-08-10 13:14:49,809 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (DefaultQuartzScheduler_Worker-20) [6efa8e9f] hostFromVds::selectedVds - h1, spmStatus SPM, storage pool Default >2014-08-10 13:14:49,818 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (DefaultQuartzScheduler_Worker-20) [6efa8e9f] Initialize Irs proxy from vds: battlestar.tlv.redhat.com >2014-08-10 13:14:49,842 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-20) [6efa8e9f] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Storage Pool Manager runs on Host h1 (Address: battlestar.tlv.redhat.com). >2014-08-10 13:14:49,859 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-13) [6efa8e9f] START, SPMGetAllTasksInfoVDSCommand( storagePoolId = 00000002-0002-0002-0002-00000000037f, ignoreFailoverLimit = false), log id: 2dfc93ef >2014-08-10 13:14:49,933 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-20) [6efa8e9f] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Warning, Low disk space.sd_35 domain has 49 GB of free space >2014-08-10 13:14:49,958 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-20) [6efa8e9f] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Warning, Low disk space.sd35_1 domain has 49 GB of free space >2014-08-10 13:14:49,959 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-13) [6efa8e9f] -- executeIrsBrokerCommand: Attempting on storage pool 00000002-0002-0002-0002-00000000037f >2014-08-10 13:14:49,967 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-13) [6efa8e9f] START, HSMGetAllTasksInfoVDSCommand(HostName = h1, HostId = 9c67d903-8424-4cda-8bb1-3d8652b92d77), log id: 2af1e871 >2014-08-10 13:14:49,974 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-13) [6efa8e9f] FINISH, HSMGetAllTasksInfoVDSCommand, return: [], log id: 2af1e871 >2014-08-10 13:14:49,974 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-13) [6efa8e9f] FINISH, SPMGetAllTasksInfoVDSCommand, return: [], log id: 2dfc93ef >2014-08-10 13:14:49,975 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-8-thread-13) [6efa8e9f] Discovered no tasks on Storage Pool Default >2014-08-10 13:15:39,641 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Loaded file "/home/vvolansk/ovirt-engine/share/ovirt-engine/services/ovirt-engine/ovirt-engine.conf". >2014-08-10 13:15:39,643 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) The file "/home/vvolansk/ovirt-engine/etc/ovirt-engine/engine.conf" doesn't exist or isn't readable. Will return an empty set of properties. >2014-08-10 13:15:39,644 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Loaded file "/home/vvolansk/ovirt-engine/etc/ovirt-engine/engine.conf.d/10-setup-database.conf". >2014-08-10 13:15:39,645 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Loaded file "/home/vvolansk/ovirt-engine/etc/ovirt-engine/engine.conf.d/10-setup-jboss.conf". >2014-08-10 13:15:39,646 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Loaded file "/home/vvolansk/ovirt-engine/etc/ovirt-engine/engine.conf.d/10-setup-pki.conf". >2014-08-10 13:15:39,647 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Loaded file "/home/vvolansk/ovirt-engine/etc/ovirt-engine/engine.conf.d/10-setup-protocols.conf". >2014-08-10 13:15:39,648 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_AJP_ENABLED" is "false". >2014-08-10 13:15:39,649 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_AJP_PORT" is "None". >2014-08-10 13:15:39,649 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_APPS" is "engine.ear restapi.war legacy_restapi.war". >2014-08-10 13:15:39,650 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_CACHE" is "/home/vvolansk/ovirt-engine/var/cache/ovirt-engine". >2014-08-10 13:15:39,650 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_CHECK_INTERVAL" is "1000". >2014-08-10 13:15:39,651 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_CONNECTION_TIMEOUT" is "300000". >2014-08-10 13:15:39,651 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_DATABASE" is "engine". >2014-08-10 13:15:39,652 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_DRIVER" is "org.postgresql.Driver". >2014-08-10 13:15:39,653 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_HOST" is "localhost". >2014-08-10 13:15:39,653 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_MAX_CONNECTIONS" is "100". >2014-08-10 13:15:39,654 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_MIN_CONNECTIONS" is "1". >2014-08-10 13:15:39,654 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_PASSWORD" is "***". >2014-08-10 13:15:39,655 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_PORT" is "5432". >2014-08-10 13:15:39,655 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_SECURED" is "False". >2014-08-10 13:15:39,656 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_SECURED_VALIDATION" is "False". >2014-08-10 13:15:39,656 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_URL" is "jdbc:postgresql://localhost:5432/engine?sslfactory=org.postgresql.ssl.NonValidatingFactory". >2014-08-10 13:15:39,657 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_USER" is "engine". >2014-08-10 13:15:39,657 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DEBUG_ADDRESS" is "127.0.0.1:8787". >2014-08-10 13:15:39,658 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DOC" is "/home/vvolansk/ovirt-engine/share/doc/ovirt-engine". >2014-08-10 13:15:39,659 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_ETC" is "/home/vvolansk/ovirt-engine/etc/ovirt-engine". >2014-08-10 13:15:39,659 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_EXTENSION_PATH" is "/home/vvolansk/ovirt-engine/share/ovirt-engine/extensions.d:/home/vvolansk/ovirt-engine/etc/ovirt-engine/extensions.d". >2014-08-10 13:15:39,660 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_FQDN" is "vered.laptop.tlv.redhat.com". >2014-08-10 13:15:39,661 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_GROUP" is "ovirt". >2014-08-10 13:15:39,662 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_HEAP_MAX" is "1g". >2014-08-10 13:15:39,662 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_HEAP_MIN" is "1g". >2014-08-10 13:15:39,663 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_HTTPS_ENABLED" is "true". >2014-08-10 13:15:39,663 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_HTTPS_PORT" is "8443". >2014-08-10 13:15:39,664 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_HTTPS_PROTOCOLS" is "SSLv3,TLSv1,TLSv1.1,TLSv1.2". >2014-08-10 13:15:39,664 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_HTTP_ENABLED" is "true". >2014-08-10 13:15:39,665 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_HTTP_PORT" is "8080". >2014-08-10 13:15:39,665 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_JAVA_MODULEPATH" is "/home/vvolansk/ovirt-engine/share/ovirt-engine/modules". >2014-08-10 13:15:39,666 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_JVM_ARGS" is " -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath="/home/vvolansk/ovirt-engine/var/log/ovirt-engine/dump"". >2014-08-10 13:15:39,667 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_LOG" is "/home/vvolansk/ovirt-engine/var/log/ovirt-engine". >2014-08-10 13:15:39,667 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_LOG_TO_CONSOLE" is "true". >2014-08-10 13:15:39,668 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_MANUAL" is "/home/vvolansk/ovirt-engine/share/ovirt-engine/manual". >2014-08-10 13:15:39,668 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PERM_MAX" is "256m". >2014-08-10 13:15:39,669 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PERM_MIN" is "256m". >2014-08-10 13:15:39,669 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI" is "/home/vvolansk/ovirt-engine/etc/pki/ovirt-engine". >2014-08-10 13:15:39,670 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI_CA" is "/home/vvolansk/ovirt-engine/etc/pki/ovirt-engine/ca.pem". >2014-08-10 13:15:39,670 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI_ENGINE_CERT" is "/home/vvolansk/ovirt-engine/etc/pki/ovirt-engine/certs/engine.cer". >2014-08-10 13:15:39,671 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI_ENGINE_STORE" is "/home/vvolansk/ovirt-engine/etc/pki/ovirt-engine/keys/engine.p12". >2014-08-10 13:15:39,671 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI_ENGINE_STORE_ALIAS" is "1". >2014-08-10 13:15:39,672 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI_ENGINE_STORE_PASSWORD" is "***". >2014-08-10 13:15:39,672 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI_ENGINE_STORE_TYPE" is "PKCS12". >2014-08-10 13:15:39,673 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI_TRUST_STORE" is "/home/vvolansk/ovirt-engine/etc/pki/ovirt-engine/.truststore". >2014-08-10 13:15:39,674 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI_TRUST_STORE_PASSWORD" is "***". >2014-08-10 13:15:39,674 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI_TRUST_STORE_TYPE" is "JKS". >2014-08-10 13:15:39,675 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PROPERTIES" is " jsse.enableSNIExtension=false "java.security.krb5.conf=/home/vvolansk/ovirt-engine/etc/ovirt-engine/krb5.conf"". >2014-08-10 13:15:39,675 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PROXY_ENABLED" is "false". >2014-08-10 13:15:39,676 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PROXY_HTTPS_PORT" is "443". >2014-08-10 13:15:39,676 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PROXY_HTTP_PORT" is "80". >2014-08-10 13:15:39,677 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_REPORTS_UI" is "/home/vvolansk/ovirt-engine/var/lib/ovirt-engine/reports.xml". >2014-08-10 13:15:39,678 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_STOP_INTERVAL" is "1". >2014-08-10 13:15:39,678 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_STOP_TIME" is "10". >2014-08-10 13:15:39,678 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_TMP" is "/home/vvolansk/ovirt-engine/var/tmp/ovirt-engine". >2014-08-10 13:15:39,679 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_UP_MARK" is "/home/vvolansk/ovirt-engine/var/lib/ovirt-engine/engine.up". >2014-08-10 13:15:39,680 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_URI" is "/ovirt-engine". >2014-08-10 13:15:39,680 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_USER" is "ovirt". >2014-08-10 13:15:39,681 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_USR" is "/home/vvolansk/ovirt-engine/share/ovirt-engine". >2014-08-10 13:15:39,681 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_VAR" is "/home/vvolansk/ovirt-engine/var/lib/ovirt-engine". >2014-08-10 13:15:39,683 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_VERBOSE_GC" is "false". >2014-08-10 13:15:39,686 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "JBOSS_HOME" is "/usr/share/jboss-as". >2014-08-10 13:15:39,686 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "JBOSS_RUNTIME" is "/home/vvolansk/ovirt-engine/var/lib/ovirt-engine/jboss_runtime". >2014-08-10 13:15:39,687 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "SENSITIVE_KEYS" is ",ENGINE_DB_PASSWORD,ENGINE_PKI_TRUST_STORE_PASSWORD,ENGINE_PKI_ENGINE_STORE_PASSWORD". >2014-08-10 13:15:40,058 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-8) Start initializing Backend >2014-08-10 13:15:40,184 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-8) Failed to decrypt value for property AdminPassword will be used encrypted value: javax.crypto.BadPaddingException: Data must start with zero > at sun.security.rsa.RSAPadding.unpadV15(RSAPadding.java:325) [rt.jar:1.7.0_25] > at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:272) [rt.jar:1.7.0_25] > at com.sun.crypto.provider.RSACipher.doFinal(RSACipher.java:356) > at com.sun.crypto.provider.RSACipher.engineDoFinal(RSACipher.java:382) > at javax.crypto.Cipher.doFinal(Cipher.java:1922) [jce.jar:1.7.0_25] > at org.ovirt.engine.core.utils.crypt.EngineEncryptionUtils.decrypt(EngineEncryptionUtils.java:163) [utils.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.getValue(DBConfigUtils.java:112) [dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.updateOption(DBConfigUtils.java:263) [dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.refreshVdcOptionCache(DBConfigUtils.java:38) [dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.<init>(DBConfigUtils.java:171) [dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.<init>(DBConfigUtils.java:157) [dal.jar:] > at org.ovirt.engine.core.bll.Backend.initialize(Backend.java:187) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.create(Backend.java:138) [bll.jar:] > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_25] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_25] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_25] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_25] > at org.jboss.as.ee.component.ManagedReferenceLifecycleMethodInterceptorFactory$ManagedReferenceLifecycleMethodInterceptor.processInvocation(ManagedReferenceLifecycleMethodInterceptorFactory.java:130) [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.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.weld.injection.WeldInjectionInterceptor.processInvocation(WeldInjectionInterceptor.java:73) [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.as.ee.component.ManagedReferenceInterceptorFactory$ManagedReferenceInterceptor.processInvocation(ManagedReferenceInterceptorFactory.java:95) [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.ManagedReferenceInterceptorFactory$ManagedReferenceInterceptor.processInvocation(ManagedReferenceInterceptorFactory.java:95) [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.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.ejb3.tx.CMTTxInterceptor.invokeInOurTx(CMTTxInterceptor.java:228) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.requiresNew(CMTTxInterceptor.java:333) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.SingletonLifecycleCMTTxInterceptor.processInvocation(SingletonLifecycleCMTTxInterceptor.java:56) [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.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.BasicComponent.constructComponentInstance(BasicComponent.java:161) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.BasicComponent.createInstance(BasicComponent.java:85) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.component.singleton.SingletonComponent.getComponentInstance(SingletonComponent.java:116) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.component.singleton.SingletonComponent.start(SingletonComponent.java:130) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.ComponentStartService.start(ComponentStartService.java:44) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1811) > at org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1746) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_25] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_25] > at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25] > >2014-08-10 13:15:40,296 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-8) Failed to decrypt value for property LocalAdminPassword will be used encrypted value: javax.crypto.BadPaddingException: Data must start with zero > at sun.security.rsa.RSAPadding.unpadV15(RSAPadding.java:325) [rt.jar:1.7.0_25] > at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:272) [rt.jar:1.7.0_25] > at com.sun.crypto.provider.RSACipher.doFinal(RSACipher.java:356) > at com.sun.crypto.provider.RSACipher.engineDoFinal(RSACipher.java:382) > at javax.crypto.Cipher.doFinal(Cipher.java:1922) [jce.jar:1.7.0_25] > at org.ovirt.engine.core.utils.crypt.EngineEncryptionUtils.decrypt(EngineEncryptionUtils.java:163) [utils.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.getValue(DBConfigUtils.java:112) [dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.updateOption(DBConfigUtils.java:263) [dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.refreshVdcOptionCache(DBConfigUtils.java:38) [dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.<init>(DBConfigUtils.java:171) [dal.jar:] > at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.<init>(DBConfigUtils.java:157) [dal.jar:] > at org.ovirt.engine.core.bll.Backend.initialize(Backend.java:187) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.create(Backend.java:138) [bll.jar:] > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_25] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_25] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_25] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_25] > at org.jboss.as.ee.component.ManagedReferenceLifecycleMethodInterceptorFactory$ManagedReferenceLifecycleMethodInterceptor.processInvocation(ManagedReferenceLifecycleMethodInterceptorFactory.java:130) [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.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.weld.injection.WeldInjectionInterceptor.processInvocation(WeldInjectionInterceptor.java:73) [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.as.ee.component.ManagedReferenceInterceptorFactory$ManagedReferenceInterceptor.processInvocation(ManagedReferenceInterceptorFactory.java:95) [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.ManagedReferenceInterceptorFactory$ManagedReferenceInterceptor.processInvocation(ManagedReferenceInterceptorFactory.java:95) [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.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.ejb3.tx.CMTTxInterceptor.invokeInOurTx(CMTTxInterceptor.java:228) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.requiresNew(CMTTxInterceptor.java:333) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.SingletonLifecycleCMTTxInterceptor.processInvocation(SingletonLifecycleCMTTxInterceptor.java:56) [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.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.BasicComponent.constructComponentInstance(BasicComponent.java:161) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.BasicComponent.createInstance(BasicComponent.java:85) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.component.singleton.SingletonComponent.getComponentInstance(SingletonComponent.java:116) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.component.singleton.SingletonComponent.start(SingletonComponent.java:130) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.ComponentStartService.start(ComponentStartService.java:44) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1811) > at org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1746) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_25] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_25] > at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25] > >2014-08-10 13:15:40,337 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-8) Could not parse option AutoRecoveryAllowedTypes value. >2014-08-10 13:15:40,350 INFO [org.ovirt.engine.core.utils.osinfo.OsInfoPreferencesLoader] (MSC service thread 1-8) Loaded file /home/vvolansk/ovirt-engine/etc/ovirt-engine/osinfo.conf.d/00-defaults.properties >2014-08-10 13:15:40,493 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-8) Running ovirt-engine 3.5.0.0 >2014-08-10 13:15:40,494 INFO [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-8) Start initializing dictionaries >2014-08-10 13:15:40,497 INFO [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-8) Finished initializing dictionaries >2014-08-10 13:15:40,498 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-8) Start initializing AuditLogCleanupManager >2014-08-10 13:15:40,499 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-8) Setting audit cleanup manager to run at: 35 35 3 * * ? >2014-08-10 13:15:40,518 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-8) Finished initializing AuditLogCleanupManager >2014-08-10 13:15:40,518 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-8) Start initializing CommandEntityCleanupManager >2014-08-10 13:15:40,519 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-8) Setting command entity cleanup manager to run at: 35 35 3 * * ? >2014-08-10 13:15:40,520 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-8) Finished initializing CommandEntityCleanupManager >2014-08-10 13:15:40,525 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-8) Start initializing TagsDirector >2014-08-10 13:15:40,536 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-8) Tag root added to tree >2014-08-10 13:15:40,544 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-8) Finished initializing TagsDirector >2014-08-10 13:15:40,545 INFO [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-8) Start initializing IsoDomainListSyncronizer >2014-08-10 13:15:40,553 INFO [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-8) Finished initializing IsoDomainListSyncronizer >2014-08-10 13:15:40,621 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-8) Completed initializing handlers >2014-08-10 13:15:40,641 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-8) Start initializing ErrorTranslatorImpl >2014-08-10 13:15:40,653 WARN [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-8) Code MAC_ADDRESS_IS_IN_USE appears more than once in string table. >2014-08-10 13:15:40,655 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-8) Finished initializing ErrorTranslatorImpl >2014-08-10 13:15:40,655 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-8) Start initializing ErrorTranslatorImpl >2014-08-10 13:15:40,656 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-8) Finished initializing ErrorTranslatorImpl >2014-08-10 13:15:40,657 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-8) Mark incomplete jobs as UNKNOWN >2014-08-10 13:15:40,682 INFO [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-8) Start initializing JobRepositoryCleanupManager >2014-08-10 13:15:40,683 INFO [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-8) Finished initializing JobRepositoryCleanupManager >2014-08-10 13:15:40,683 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-8) Start initializing AutoRecoveryManager >2014-08-10 13:15:40,685 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-8) Finished initializing AutoRecoveryManager >2014-08-10 13:15:40,686 INFO [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-8) Start initializing ExecutionMessageDirector >2014-08-10 13:15:40,688 INFO [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-8) Finished initializing ExecutionMessageDirector >2014-08-10 13:15:40,734 INFO [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-8) Start of enabled extensions list >2014-08-10 13:15:40,735 INFO [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-8) 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-08-10 13:15:40,736 INFO [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-8) 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-08-10 13:15:40,737 INFO [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-8) End of enabled extensions list >2014-08-10 13:15:40,794 INFO [org.ovirt.engine.core.bll.aaa.DbUserCacheManager] (MSC service thread 1-7) Start initializing DbUserCacheManager >2014-08-10 13:15:40,795 INFO [org.ovirt.engine.core.bll.aaa.DbUserCacheManager] (MSC service thread 1-7) Finished initializing DbUserCacheManager >2014-08-10 13:15:40,805 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (MSC service thread 1-7) Initialization of AsyncTaskManager completed successfully. >2014-08-10 13:15:40,808 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-7) Start initializing ResourceManager >2014-08-10 13:15:40,824 INFO [org.ovirt.engine.core.bll.aaa.SyncUsers] (DefaultQuartzScheduler_Worker-1) Principal admin::internal synchronized >2014-08-10 13:15:40,888 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-7) Entered VdsManager constructor >2014-08-10 13:15:40,907 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-7) Initialize vdsBroker (battlestar.tlv.redhat.com,54,321) >2014-08-10 13:15:40,983 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-7) VDS 9c67d903-8424-4cda-8bb1-3d8652b92d77 was added to the Resource Manager >2014-08-10 13:15:40,996 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-7) Finished initializing ResourceManager >2014-08-10 13:15:41,000 INFO [org.ovirt.engine.core.bll.OvfDataUpdater] (MSC service thread 1-7) Initialization of OvfDataUpdater completed successfully. >2014-08-10 13:15:41,000 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-7) Start scheduling to enable vds load balancer >2014-08-10 13:15:41,001 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-7) Finished scheduling to enable vds load balancer >2014-08-10 13:15:41,001 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-7) Start HA Reservation check >2014-08-10 13:15:41,002 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-7) Finished HA Reservation check >2014-08-10 13:15:41,008 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-7) Init VM custom properties utilities >2014-08-10 13:15:41,009 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-7) Init device custom properties utilities >2014-08-10 13:15:41,014 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-7) Initializing Scheduling manager >2014-08-10 13:15:41,042 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-7) External scheduler disabled, discovery skipped >2014-08-10 13:15:41,042 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-7) Initialized Scheduling manager >2014-08-10 13:15:41,043 INFO [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-7) Initializing DWH Heart Beat >2014-08-10 13:15:41,044 INFO [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-7) DWH Heart Beat initialized >2014-08-10 13:15:41,048 INFO [org.ovirt.engine.core.bll.network.macpoolmanager.MacPoolManagerRanges] (org.ovirt.thread.pool-8-thread-1) Start initializing MacPoolManagerRanges >2014-08-10 13:15:41,050 INFO [org.ovirt.engine.core.bll.network.macpoolmanager.MacPoolManagerRanges] (org.ovirt.thread.pool-8-thread-1) Finished initializing. Available MACs in pool: 150 >2014-08-10 13:15:41,050 INFO [org.ovirt.engine.core.bll.network.macpoolmanager.MacPoolPerDc] (org.ovirt.thread.pool-8-thread-1) successfully initialized >2014-08-10 13:15:44,623 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-6) START, GetHardwareInfoVDSCommand(HostName = h1, HostId = 9c67d903-8424-4cda-8bb1-3d8652b92d77, vds=Host[h1,9c67d903-8424-4cda-8bb1-3d8652b92d77]), log id: 21eeb0f >2014-08-10 13:15:44,633 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-6) FINISH, GetHardwareInfoVDSCommand, log id: 21eeb0f >2014-08-10 13:15:44,645 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) 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-08-10 13:15:44,651 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: UNASSIGNED not exist in string table >2014-08-10 13:15:44,652 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: VDS_AUTO_FENCE_STATUS not exist in string table >2014-08-10 13:15:44,652 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: VDS_AUTO_FENCE_STATUS_FAILED not exist in string table >2014-08-10 13:15:44,653 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: VDS_AUTO_FENCE_FAILED_CALL_FENCE_SPM not exist in string table >2014-08-10 13:15:44,653 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: VDS_HIGH_NETWORK_USE not exist in string table >2014-08-10 13:15:44,654 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: VDS_NOT_RESTARTED_DUE_TO_POLICY not exist in string table >2014-08-10 13:15:44,655 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_FAILED_REMOVE_VM not exist in string table >2014-08-10 13:15:44,656 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_RUN_UNLOCK_ENTITY_SCRIPT not exist in string table >2014-08-10 13:15:44,657 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: VDS_NETWORK_MTU_DIFFER_FROM_LOGICAL_NETWORK not exist in string table >2014-08-10 13:15:44,658 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: VDS_HOST_IN_CONNECTING_STATE not exist in string table >2014-08-10 13:15:44,659 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: STORAGE_ACTIVATE_ASYNC not exist in string table >2014-08-10 13:15:44,659 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: DWH_STOPPED not exist in string table >2014-08-10 13:15:44,660 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: DWH_STARTED not exist in string table >2014-08-10 13:15:44,661 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: DWH_ERROR not exist in string table >2014-08-10 13:15:44,661 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_REMOVE_AUDIT_LOG not exist in string table >2014-08-10 13:15:44,662 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_REMOVE_AUDIT_LOG_FAILED not exist in string table >2014-08-10 13:15:44,662 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_CLEAR_ALL_DISMISSED_AUDIT_LOG not exist in string table >2014-08-10 13:15:44,663 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_CLEAR_ALL_DISMISSED_AUDIT_LOG_FAILED not exist in string table >2014-08-10 13:15:44,811 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Host h1 does not enforce SELinux. >2014-08-10 13:15:44,812 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-6) Host h1 is running with disabled SELinux. >2014-08-10 13:15:45,198 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-6) Initializing Host: h1 >2014-08-10 13:15:45,293 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (DefaultQuartzScheduler_Worker-6) [5569b6c0] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: 9c67d903-8424-4cda-8bb1-3d8652b92d77 Type: VDS >2014-08-10 13:15:51,039 INFO [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (DefaultQuartzScheduler_Worker-19) [288657e1] Running command: SetStoragePoolStatusCommand internal: true. Entities affected : ID: 00000002-0002-0002-0002-00000000037f Type: StoragePool >2014-08-10 13:15:51,048 INFO [org.ovirt.engine.core.vdsbroker.storage.StoragePoolDomainHelper] (DefaultQuartzScheduler_Worker-19) [288657e1] Storage Pool 00000002-0002-0002-0002-00000000037f - Updating Storage Domain d54e6e6c-81f2-41f2-90ab-7e3c2457938a status from Active to Unknown, reason : null >2014-08-10 13:15:51,053 INFO [org.ovirt.engine.core.vdsbroker.storage.StoragePoolDomainHelper] (DefaultQuartzScheduler_Worker-19) [288657e1] Storage Pool 00000002-0002-0002-0002-00000000037f - Updating Storage Domain 7158eae9-4886-4af7-af0b-d668ecee9e4f status from Active to Unknown, reason : null >2014-08-10 13:15:51,114 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-19) [288657e1] Correlation ID: 288657e1, Call Stack: null, Custom Event ID: -1, Message: Invalid status on Data Center Default. Setting status to Non Responsive. >2014-08-10 13:15:51,166 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (DefaultQuartzScheduler_Worker-19) [288657e1] hostFromVds::selectedVds - h1, spmStatus SPM, storage pool Default >2014-08-10 13:15:51,213 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (DefaultQuartzScheduler_Worker-19) [288657e1] Initialize Irs proxy from vds: battlestar.tlv.redhat.com >2014-08-10 13:15:51,304 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-19) [288657e1] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Storage Pool Manager runs on Host h1 (Address: battlestar.tlv.redhat.com). >2014-08-10 13:15:51,324 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-13) [288657e1] START, SPMGetAllTasksInfoVDSCommand( storagePoolId = 00000002-0002-0002-0002-00000000037f, ignoreFailoverLimit = false), log id: 32947053 >2014-08-10 13:15:51,420 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-19) [288657e1] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Warning, Low disk space.sd_35 domain has 49 GB of free space >2014-08-10 13:15:51,445 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-19) [288657e1] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Warning, Low disk space.sd35_1 domain has 49 GB of free space >2014-08-10 13:15:51,446 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-13) [288657e1] -- executeIrsBrokerCommand: Attempting on storage pool 00000002-0002-0002-0002-00000000037f >2014-08-10 13:15:51,456 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-13) [288657e1] START, HSMGetAllTasksInfoVDSCommand(HostName = h1, HostId = 9c67d903-8424-4cda-8bb1-3d8652b92d77), log id: 31d1e747 >2014-08-10 13:15:51,464 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-13) [288657e1] FINISH, HSMGetAllTasksInfoVDSCommand, return: [], log id: 31d1e747 >2014-08-10 13:15:51,465 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-13) [288657e1] FINISH, SPMGetAllTasksInfoVDSCommand, return: [], log id: 32947053 >2014-08-10 13:15:51,466 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-8-thread-13) [288657e1] Discovered no tasks on Storage Pool Default >2014-08-10 13:15:52,441 INFO [org.ovirt.engine.core.bll.aaa.LoginAdminUserCommand] (http--0.0.0.0-8080-1) Running command: LoginAdminUserCommand internal: false. >2014-08-10 13:15:52,472 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin logged in. >2014-08-10 13:15:55,937 INFO [org.ovirt.engine.core.bll.aaa.LoginUserCommand] (http--0.0.0.0-8080-4) Running command: LoginUserCommand internal: false. >2014-08-10 13:16:02,125 INFO [org.ovirt.engine.core.bll.HibernateVmCommand] (http--0.0.0.0-8080-1) [3b5aec92] Lock Acquired to object EngineLock [exclusiveLocks= key: de580b19-e975-426f-80de-6c7daf1f24ab value: VM >, sharedLocks= ] >2014-08-10 13:16:02,410 INFO [org.ovirt.engine.core.bll.HibernateVmCommand] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] Running command: HibernateVmCommand internal: false. Entities affected : ID: de580b19-e975-426f-80de-6c7daf1f24ab Type: VMAction group VM_BASIC_OPERATIONS with role type USER >2014-08-10 13:16:02,460 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] START, UpdateVmDynamicDataVDSCommand(HostName = h1, HostId = 9c67d903-8424-4cda-8bb1-3d8652b92d77, vmDynamic=org.ovirt.engine.core.common.businessentities.VmDynamic@afbeab9b), log id: 73d013e9 >2014-08-10 13:16:02,463 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] FINISH, UpdateVmDynamicDataVDSCommand, log id: 73d013e9 >2014-08-10 13:16:02,478 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] START, CreateImageVDSCommand( storagePoolId = 00000002-0002-0002-0002-00000000037f, ignoreFailoverLimit = false, storageDomainId = 7158eae9-4886-4af7-af0b-d668ecee9e4f, imageGroupId = ef7c8ef2-cf66-4f60-a752-f1a79235e2d6, imageSizeInBytes = 1350565888, volumeFormat = RAW, newImageId = 952565e4-a91d-4876-a3b1-ccd5dfe2a8b0, newImageDescription = ), log id: 7278a6e9 >2014-08-10 13:16:02,490 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID >2014-08-10 13:16:02,937 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] FINISH, CreateImageVDSCommand, return: 952565e4-a91d-4876-a3b1-ccd5dfe2a8b0, log id: 7278a6e9 >2014-08-10 13:16:02,969 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 8ae74d40-2c2e-4226-9dd4-08ed57a8d4ad >2014-08-10 13:16:02,970 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] CommandMultiAsyncTasks::AttachTask: Attaching task 6af997dd-7966-4fa6-8a79-5e5e278f0ef4 to command 8ae74d40-2c2e-4226-9dd4-08ed57a8d4ad. >2014-08-10 13:16:02,990 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] Adding task 6af997dd-7966-4fa6-8a79-5e5e278f0ef4 (Parent Command HibernateVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-08-10 13:16:03,016 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] START, CreateImageVDSCommand( storagePoolId = 00000002-0002-0002-0002-00000000037f, ignoreFailoverLimit = false, storageDomainId = 7158eae9-4886-4af7-af0b-d668ecee9e4f, imageGroupId = 4bd8a1f0-e234-40d6-a71c-9a93818794e4, imageSizeInBytes = 10240, volumeFormat = COW, newImageId = bb223e58-a6b3-449a-a77a-344b4863850b, newImageDescription = ), log id: 3d7d67fa >2014-08-10 13:16:03,018 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID >2014-08-10 13:16:03,730 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] FINISH, CreateImageVDSCommand, return: bb223e58-a6b3-449a-a77a-344b4863850b, log id: 3d7d67fa >2014-08-10 13:16:03,839 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] CommandMultiAsyncTasks::AttachTask: Attaching task 5812d8ec-cc9a-412f-8005-99c507282276 to command 8ae74d40-2c2e-4226-9dd4-08ed57a8d4ad. >2014-08-10 13:16:03,869 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] Adding task 5812d8ec-cc9a-412f-8005-99c507282276 (Parent Command HibernateVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-08-10 13:16:03,886 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] START, UpdateVmDynamicDataVDSCommand(HostName = h1, HostId = 9c67d903-8424-4cda-8bb1-3d8652b92d77, vmDynamic=org.ovirt.engine.core.common.businessentities.VmDynamic@b6e8248c), log id: 420e3434 >2014-08-10 13:16:03,934 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] FINISH, UpdateVmDynamicDataVDSCommand, log id: 420e3434 >2014-08-10 13:16:04,017 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] Correlation ID: 3b5aec92, Job ID: ca864131-793a-4525-8d09-42afef54ed7d, Call Stack: null, Custom Event ID: -1, Message: Suspending VM vm4 was initiated by User admin (Host: h1). >2014-08-10 13:16:04,018 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] BaseAsyncTask::startPollingTask: Starting to poll task 6af997dd-7966-4fa6-8a79-5e5e278f0ef4. >2014-08-10 13:16:04,019 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] BaseAsyncTask::startPollingTask: Starting to poll task 5812d8ec-cc9a-412f-8005-99c507282276. >2014-08-10 13:16:04,020 INFO [org.ovirt.engine.core.bll.HibernateVmCommand] (org.ovirt.thread.pool-8-thread-31) [3b5aec92] Lock freed to object EngineLock [exclusiveLocks= key: de580b19-e975-426f-80de-6c7daf1f24ab value: VM >, sharedLocks= ] >2014-08-10 13:16:10,797 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-60) Polling and updating Async Tasks: 2 tasks, 2 tasks to poll now >2014-08-10 13:16:10,844 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-60) SPMAsyncTask::PollTask: Polling task 5812d8ec-cc9a-412f-8005-99c507282276 (Parent Command HibernateVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-08-10 13:16:10,882 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-60) BaseAsyncTask::onTaskEndSuccess: Task 5812d8ec-cc9a-412f-8005-99c507282276 (Parent Command HibernateVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-08-10 13:16:10,884 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler_Worker-60) Task with DB Task ID 8cae746b-1044-4f73-92af-21405dd2e93b and VDSM Task ID 6af997dd-7966-4fa6-8a79-5e5e278f0ef4 is in state Polling. End action for command 8ae74d40-2c2e-4226-9dd4-08ed57a8d4ad will proceed when all the entitys tasks are completed. >2014-08-10 13:16:10,885 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-60) SPMAsyncTask::PollTask: Polling task 6af997dd-7966-4fa6-8a79-5e5e278f0ef4 (Parent Command HibernateVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-08-10 13:16:10,899 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-60) BaseAsyncTask::onTaskEndSuccess: Task 6af997dd-7966-4fa6-8a79-5e5e278f0ef4 (Parent Command HibernateVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-08-10 13:16:10,900 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-60) CommandAsyncTask::endActionIfNecessary: All tasks of command 8ae74d40-2c2e-4226-9dd4-08ed57a8d4ad has ended -> executing endAction >2014-08-10 13:16:10,901 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-60) CommandAsyncTask::endAction: Ending action for 2 tasks (command ID: 8ae74d40-2c2e-4226-9dd4-08ed57a8d4ad): calling endAction . >2014-08-10 13:16:10,902 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-41) CommandAsyncTask::endCommandAction [within thread] context: Attempting to endAction HibernateVm, executionIndex: 0 >2014-08-10 13:16:10,965 INFO [org.ovirt.engine.core.bll.HibernateVmCommand] (org.ovirt.thread.pool-8-thread-41) Ending command successfully: org.ovirt.engine.core.bll.HibernateVmCommand >2014-08-10 13:16:10,982 INFO [org.ovirt.engine.core.vdsbroker.HibernateVDSCommand] (org.ovirt.thread.pool-8-thread-41) START, HibernateVDSCommand(HostName = h1, HostId = 9c67d903-8424-4cda-8bb1-3d8652b92d77, vmId=de580b19-e975-426f-80de-6c7daf1f24ab, hibernationVolHandle=7158eae9-4886-4af7-af0b-d668ecee9e4f,00000002-0002-0002-0002-00000000037f,ef7c8ef2-cf66-4f60-a752-f1a79235e2d6,952565e4-a91d-4876-a3b1-ccd5dfe2a8b0,4bd8a1f0-e234-40d6-a71c-9a93818794e4,bb223e58-a6b3-449a-a77a-344b4863850b), log id: 497b0b5d >2014-08-10 13:16:10,984 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HibernateBrokerVDSCommand] (org.ovirt.thread.pool-8-thread-41) START, HibernateBrokerVDSCommand(HostName = h1, HostId = 9c67d903-8424-4cda-8bb1-3d8652b92d77, vmId=de580b19-e975-426f-80de-6c7daf1f24ab, hibernationVolHandle=7158eae9-4886-4af7-af0b-d668ecee9e4f,00000002-0002-0002-0002-00000000037f,ef7c8ef2-cf66-4f60-a752-f1a79235e2d6,952565e4-a91d-4876-a3b1-ccd5dfe2a8b0,4bd8a1f0-e234-40d6-a71c-9a93818794e4,bb223e58-a6b3-449a-a77a-344b4863850b), log id: 12fa0a51 >2014-08-10 13:16:11,000 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HibernateBrokerVDSCommand] (org.ovirt.thread.pool-8-thread-41) FINISH, HibernateBrokerVDSCommand, log id: 12fa0a51 >2014-08-10 13:16:11,015 INFO [org.ovirt.engine.core.vdsbroker.HibernateVDSCommand] (org.ovirt.thread.pool-8-thread-41) FINISH, HibernateVDSCommand, log id: 497b0b5d >2014-08-10 13:16:11,016 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-41) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type HibernateVm completed, handling the result. >2014-08-10 13:16:11,017 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-41) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type HibernateVm succeeded, clearing tasks. >2014-08-10 13:16:11,031 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-41) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 5812d8ec-cc9a-412f-8005-99c507282276 >2014-08-10 13:16:11,035 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-41) START, SPMClearTaskVDSCommand( storagePoolId = 00000002-0002-0002-0002-00000000037f, ignoreFailoverLimit = false, taskId = 5812d8ec-cc9a-412f-8005-99c507282276), log id: 4e9ca165 >2014-08-10 13:16:11,037 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-41) START, HSMClearTaskVDSCommand(HostName = h1, HostId = 9c67d903-8424-4cda-8bb1-3d8652b92d77, taskId=5812d8ec-cc9a-412f-8005-99c507282276), log id: 36053c15 >2014-08-10 13:16:11,130 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-41) FINISH, HSMClearTaskVDSCommand, log id: 36053c15 >2014-08-10 13:16:11,130 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-41) FINISH, SPMClearTaskVDSCommand, log id: 4e9ca165 >2014-08-10 13:16:11,147 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-41) BaseAsyncTask::removeTaskFromDB: Removed task 5812d8ec-cc9a-412f-8005-99c507282276 from DataBase >2014-08-10 13:16:11,148 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-41) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 6af997dd-7966-4fa6-8a79-5e5e278f0ef4 >2014-08-10 13:16:11,148 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-41) START, SPMClearTaskVDSCommand( storagePoolId = 00000002-0002-0002-0002-00000000037f, ignoreFailoverLimit = false, taskId = 6af997dd-7966-4fa6-8a79-5e5e278f0ef4), log id: 615680be >2014-08-10 13:16:11,150 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-41) START, HSMClearTaskVDSCommand(HostName = h1, HostId = 9c67d903-8424-4cda-8bb1-3d8652b92d77, taskId=6af997dd-7966-4fa6-8a79-5e5e278f0ef4), log id: 761e6bde >2014-08-10 13:16:11,223 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-41) FINISH, HSMClearTaskVDSCommand, log id: 761e6bde >2014-08-10 13:16:11,223 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-41) FINISH, SPMClearTaskVDSCommand, log id: 615680be >2014-08-10 13:16:11,230 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-41) BaseAsyncTask::removeTaskFromDB: Removed task 6af997dd-7966-4fa6-8a79-5e5e278f0ef4 from DataBase >2014-08-10 13:16:11,230 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-41) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 8ae74d40-2c2e-4226-9dd4-08ed57a8d4ad >2014-08-10 13:16:12,998 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-72) VM vm4 de580b19-e975-426f-80de-6c7daf1f24ab moved from SavingState --> Up >2014-08-10 13:17:40,799 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-79) Setting new tasks map. The map contains now 0 tasks >2014-08-10 13:17:40,800 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-79) Cleared all tasks of pool 00000002-0002-0002-0002-00000000037f. >2014-08-10 13:41:43,696 WARN [org.ovirt.engine.core.bll.AddVdsCommand] (http--0.0.0.0-8080-1) [5bcaa26f] Failed to initiate vdsm-id request on host with message Command returned failure code 127 during SSH session 'root@galactica.tlv.redhat.com' >2014-08-10 13:41:43,718 INFO [org.ovirt.engine.core.bll.AddVdsCommand] (http--0.0.0.0-8080-1) [5bcaa26f] Running command: AddVdsCommand internal: false. Entities affected : ID: 00000001-0001-0001-0001-00000000039f Type: VdsGroupsAction group CREATE_HOST with role type ADMIN >2014-08-10 13:41:43,753 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (http--0.0.0.0-8080-1) [e68096c] Running command: AddVdsSpmIdCommand internal: true. Entities affected : ID: 0472f9c2-dacf-4393-9fc3-5840ef369e1f Type: VDS >2014-08-10 13:41:43,785 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (http--0.0.0.0-8080-1) [e68096c] Lock freed to object EngineLock [exclusiveLocks= key: 00000002-0002-0002-0002-00000000037f value: REGISTER_VDS >, sharedLocks= ] >2014-08-10 13:41:43,788 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (http--0.0.0.0-8080-1) [e68096c] START, RemoveVdsVDSCommand(HostName = h2, HostId = 0472f9c2-dacf-4393-9fc3-5840ef369e1f), log id: 66fa7005 >2014-08-10 13:41:43,789 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (http--0.0.0.0-8080-1) [e68096c] FINISH, RemoveVdsVDSCommand, log id: 66fa7005 >2014-08-10 13:41:43,790 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (http--0.0.0.0-8080-1) [e68096c] START, AddVdsVDSCommand(HostName = h2, HostId = 0472f9c2-dacf-4393-9fc3-5840ef369e1f), log id: 1d94d886 >2014-08-10 13:41:43,791 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (http--0.0.0.0-8080-1) [e68096c] AddVds - entered , starting logic to add VDS 0472f9c2-dacf-4393-9fc3-5840ef369e1f >2014-08-10 13:41:43,792 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (http--0.0.0.0-8080-1) [e68096c] AddVds - VDS 0472f9c2-dacf-4393-9fc3-5840ef369e1f was added, will try to add it to the resource manager >2014-08-10 13:41:43,793 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (http--0.0.0.0-8080-1) [e68096c] Entered VdsManager constructor >2014-08-10 13:41:43,804 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (http--0.0.0.0-8080-1) [e68096c] Initialize vdsBroker (galactica.tlv.redhat.com,54,321) >2014-08-10 13:41:43,835 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (http--0.0.0.0-8080-1) [e68096c] VDS 0472f9c2-dacf-4393-9fc3-5840ef369e1f was added to the Resource Manager >2014-08-10 13:41:43,836 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (http--0.0.0.0-8080-1) [e68096c] FINISH, AddVdsVDSCommand, log id: 1d94d886 >2014-08-10 13:41:43,840 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [e68096c] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host h2. >2014-08-10 13:41:43,870 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] Lock Acquired to object EngineLock [exclusiveLocks= key: 0472f9c2-dacf-4393-9fc3-5840ef369e1f value: VDS >, sharedLocks= ] >2014-08-10 13:41:43,876 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [e68096c] Correlation ID: 5bcaa26f, Job ID: 883a39a1-f2d4-4989-a14f-c5224f128730, Call Stack: null, Custom Event ID: -1, Message: Host h2 was added by admin. >2014-08-10 13:41:43,901 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] Running command: InstallVdsInternalCommand internal: true. Entities affected : ID: 0472f9c2-dacf-4393-9fc3-5840ef369e1f Type: VDS >2014-08-10 13:41:43,906 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] Before Installation host 0472f9c2-dacf-4393-9fc3-5840ef369e1f, h2 >2014-08-10 13:41:43,910 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] START, SetVdsStatusVDSCommand(HostName = h2, HostId = 0472f9c2-dacf-4393-9fc3-5840ef369e1f, status=Installing, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 5aa57f9d >2014-08-10 13:41:43,917 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] FINISH, SetVdsStatusVDSCommand, log id: 5aa57f9d >2014-08-10 13:41:43,955 INFO [org.ovirt.engine.core.bll.InstallerMessages] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] Installation galactica.tlv.redhat.com: Connected to host galactica.tlv.redhat.com with SSH key fingerprint: af:6b:19:0c:de:1c:76:eb:a5:97:b3:fa:05:62:84:04 >2014-08-10 13:41:43,975 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] Correlation ID: 450fbc5c, Call Stack: null, Custom Event ID: -1, Message: Installing Host h2. Connected to host galactica.tlv.redhat.com with SSH key fingerprint: af:6b:19:0c:de:1c:76:eb:a5:97:b3:fa:05:62:84:04. >2014-08-10 13:41:44,035 INFO [org.ovirt.engine.core.bll.VdsDeploy] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] Installation of galactica.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 < /home/vvolansk/ovirt-engine/var/cache/ovirt-engine/ovirt-host-deploy.tar >2014-08-10 13:41:44,183 INFO [org.ovirt.engine.core.utils.archivers.tar.CachedTar] (org.ovirt.thread.pool-8-thread-1) Tarball '/home/vvolansk/ovirt-engine/var/cache/ovirt-engine/ovirt-host-deploy.tar' is out of date, re-creating >2014-08-10 13:41:45,118 INFO [org.ovirt.engine.core.uutils.ssh.SSHDialog] (org.ovirt.thread.pool-8-thread-1) SSH execute root@galactica.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-08-10 13:41:45,143 ERROR [org.ovirt.engine.core.bll.VdsDeploy] (VdsDeploy) Error during deploy dialog: java.io.IOException: Unexpected connection termination > at org.ovirt.otopi.dialog.MachineDialogParser.nextEvent(MachineDialogParser.java:388) [otopi.jar:] > at org.ovirt.otopi.dialog.MachineDialogParser.nextEvent(MachineDialogParser.java:405) [otopi.jar:] > at org.ovirt.engine.core.bll.VdsDeploy._threadMain(VdsDeploy.java:818) [bll.jar:] > at org.ovirt.engine.core.bll.VdsDeploy.access$2000(VdsDeploy.java:83) [bll.jar:] > at org.ovirt.engine.core.bll.VdsDeploy$51.run(VdsDeploy.java:966) [bll.jar:] > at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25] > >2014-08-10 13:41:45,144 ERROR [org.ovirt.engine.core.uutils.ssh.SSHDialog] (org.ovirt.thread.pool-8-thread-1) Swallowing exception as preferring stderr: java.io.IOException: Command returned failure code 127 during SSH session 'root@galactica.tlv.redhat.com' > at org.ovirt.engine.core.uutils.ssh.SSHClient.executeCommand(SSHClient.java:527) [uutils.jar:] > at org.ovirt.engine.core.uutils.ssh.SSHDialog.executeCommand(SSHDialog.java:318) [uutils.jar:] > at org.ovirt.engine.core.bll.VdsDeploy.execute(VdsDeploy.java:1115) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsInternalCommand.installHost(InstallVdsInternalCommand.java:151) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsInternalCommand.executeCommand(InstallVdsInternalCommand.java:78) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1163) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1290) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1910) [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:1311) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:347) [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_25] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_25] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_25] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_25] > 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:2239) [bll.jar:] > at org.ovirt.engine.core.bll.AddVdsCommand$3.run(AddVdsCommand.java:195) [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_25] > at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) [rt.jar:1.7.0_25] > at java.util.concurrent.FutureTask.run(FutureTask.java:166) [rt.jar:1.7.0_25] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_25] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_25] > at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25] > >2014-08-10 13:41:45,184 ERROR [org.ovirt.engine.core.uutils.ssh.SSHDialog] (org.ovirt.thread.pool-8-thread-1) SSH error running command root@galactica.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': java.lang.RuntimeException: Unexpected error during execution: bash: tar: command not found > > at org.ovirt.engine.core.uutils.ssh.SSHDialog.executeCommand(SSHDialog.java:337) [uutils.jar:] > at org.ovirt.engine.core.bll.VdsDeploy.execute(VdsDeploy.java:1115) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsInternalCommand.installHost(InstallVdsInternalCommand.java:151) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsInternalCommand.executeCommand(InstallVdsInternalCommand.java:78) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1163) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1290) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1910) [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:1311) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:347) [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_25] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_25] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_25] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_25] > 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:2239) [bll.jar:] > at org.ovirt.engine.core.bll.AddVdsCommand$3.run(AddVdsCommand.java:195) [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_25] > at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) [rt.jar:1.7.0_25] > at java.util.concurrent.FutureTask.run(FutureTask.java:166) [rt.jar:1.7.0_25] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_25] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_25] > at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25] > >2014-08-10 13:41:45,204 ERROR [org.ovirt.engine.core.bll.VdsDeploy] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] Error during host galactica.tlv.redhat.com install: java.lang.RuntimeException: Unexpected error during execution: bash: tar: command not found > > at org.ovirt.engine.core.uutils.ssh.SSHDialog.executeCommand(SSHDialog.java:337) [uutils.jar:] > at org.ovirt.engine.core.bll.VdsDeploy.execute(VdsDeploy.java:1115) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsInternalCommand.installHost(InstallVdsInternalCommand.java:151) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsInternalCommand.executeCommand(InstallVdsInternalCommand.java:78) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1163) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1290) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1910) [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:1311) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:347) [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_25] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_25] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_25] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_25] > 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:2239) [bll.jar:] > at org.ovirt.engine.core.bll.AddVdsCommand$3.run(AddVdsCommand.java:195) [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_25] > at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) [rt.jar:1.7.0_25] > at java.util.concurrent.FutureTask.run(FutureTask.java:166) [rt.jar:1.7.0_25] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_25] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_25] > at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25] > >2014-08-10 13:41:45,219 ERROR [org.ovirt.engine.core.bll.InstallerMessages] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] Installation galactica.tlv.redhat.com: Unexpected error during execution: bash: tar: command not found > >2014-08-10 13:41:45,242 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] Correlation ID: 450fbc5c, Call Stack: null, Custom Event ID: -1, Message: Failed to install Host h2. Unexpected error during execution: bash: tar: command not found >. >2014-08-10 13:41:45,243 ERROR [org.ovirt.engine.core.bll.VdsDeploy] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] Error during host galactica.tlv.redhat.com install, prefering first exception: java.io.IOException: Unexpected connection termination > at org.ovirt.otopi.dialog.MachineDialogParser.nextEvent(MachineDialogParser.java:388) [otopi.jar:] > at org.ovirt.otopi.dialog.MachineDialogParser.nextEvent(MachineDialogParser.java:405) [otopi.jar:] > at org.ovirt.engine.core.bll.VdsDeploy._threadMain(VdsDeploy.java:818) [bll.jar:] > at org.ovirt.engine.core.bll.VdsDeploy.access$2000(VdsDeploy.java:83) [bll.jar:] > at org.ovirt.engine.core.bll.VdsDeploy$51.run(VdsDeploy.java:966) [bll.jar:] > at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25] > >2014-08-10 13:41:45,244 ERROR [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] Host installation failed for host 0472f9c2-dacf-4393-9fc3-5840ef369e1f, h2.: java.io.IOException: Unexpected connection termination > at org.ovirt.otopi.dialog.MachineDialogParser.nextEvent(MachineDialogParser.java:388) [otopi.jar:] > at org.ovirt.otopi.dialog.MachineDialogParser.nextEvent(MachineDialogParser.java:405) [otopi.jar:] > at org.ovirt.engine.core.bll.VdsDeploy._threadMain(VdsDeploy.java:818) [bll.jar:] > at org.ovirt.engine.core.bll.VdsDeploy.access$2000(VdsDeploy.java:83) [bll.jar:] > at org.ovirt.engine.core.bll.VdsDeploy$51.run(VdsDeploy.java:966) [bll.jar:] > at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25] > >2014-08-10 13:41:45,247 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] START, SetVdsStatusVDSCommand(HostName = h2, HostId = 0472f9c2-dacf-4393-9fc3-5840ef369e1f, status=InstallFailed, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 24a545a1 >2014-08-10 13:41:45,259 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] FINISH, SetVdsStatusVDSCommand, log id: 24a545a1 >2014-08-10 13:41:45,275 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] Correlation ID: 450fbc5c, Job ID: 883a39a1-f2d4-4989-a14f-c5224f128730, Call Stack: null, Custom Event ID: -1, Message: Host h2 installation failed. Unexpected connection termination. >2014-08-10 13:41:45,284 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-1) [450fbc5c] Lock freed to object EngineLock [exclusiveLocks= key: 0472f9c2-dacf-4393-9fc3-5840ef369e1f value: VDS >, sharedLocks= ] >2014-08-10 13:41:46,836 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-40) Initializing Host: h2 >2014-08-10 13:42:00,698 WARN [org.ovirt.engine.core.bll.MaintenanceNumberOfVdssCommand] (http--0.0.0.0-8080-1) [4c70eca8] CanDoAction of action MaintenanceNumberOfVdss failed. Reasons:VAR__TYPE__HOST,VAR__ACTION__MAINTENANCE,VDS_CANNOT_MAINTENANCE_VDS_IS_NOT_OPERATIONAL >2014-08-10 13:42:08,678 INFO [org.ovirt.engine.core.bll.RemoveVdsCommand] (http--0.0.0.0-8080-1) [3404c93e] Lock Acquired to object EngineLock [exclusiveLocks= key: 0472f9c2-dacf-4393-9fc3-5840ef369e1f value: VDS >, sharedLocks= ] >2014-08-10 13:42:08,686 INFO [org.ovirt.engine.core.bll.RemoveVdsCommand] (org.ovirt.thread.pool-8-thread-38) [3404c93e] Running command: RemoveVdsCommand internal: false. Entities affected : ID: 0472f9c2-dacf-4393-9fc3-5840ef369e1f Type: VDSAction group DELETE_HOST with role type ADMIN >2014-08-10 13:42:08,815 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (org.ovirt.thread.pool-8-thread-38) [3404c93e] START, RemoveVdsVDSCommand( HostId = 0472f9c2-dacf-4393-9fc3-5840ef369e1f), log id: 20169f4c >2014-08-10 13:42:08,815 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (org.ovirt.thread.pool-8-thread-38) [3404c93e] vdsManager::disposing >2014-08-10 13:42:08,821 INFO [org.ovirt.vdsm.jsonrpc.client.reactors.stomp.StompCommonClient] (org.ovirt.thread.pool-8-thread-38) Message sent: UNSUBSCRIBE >id:null > > >2014-08-10 13:42:08,821 INFO [org.ovirt.vdsm.jsonrpc.client.reactors.stomp.StompCommonClient] (org.ovirt.thread.pool-8-thread-38) Message sent: DISCONNECT >receipt:cbb14eb7-69da-4b45-a872-d91ea2f59a68 > > >2014-08-10 13:42:08,822 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (org.ovirt.thread.pool-8-thread-38) [3404c93e] FINISH, RemoveVdsVDSCommand, log id: 20169f4c >2014-08-10 13:42:08,830 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-38) [3404c93e] Correlation ID: 3404c93e, Call Stack: null, Custom Event ID: -1, Message: Host h2 was removed by admin. >2014-08-10 13:42:08,831 INFO [org.ovirt.engine.core.bll.RemoveVdsCommand] (org.ovirt.thread.pool-8-thread-38) [3404c93e] Lock freed to object EngineLock [exclusiveLocks= key: 0472f9c2-dacf-4393-9fc3-5840ef369e1f value: VDS >, sharedLocks= ] >2014-08-10 13:45:13,237 WARN [org.ovirt.engine.core.bll.AddVdsCommand] (http--0.0.0.0-8080-1) [65960527] Failed to initiate vdsm-id request on host with message Command returned failure code 127 during SSH session 'root@galactica.tlv.redhat.com' >2014-08-10 13:45:13,264 INFO [org.ovirt.engine.core.bll.AddVdsCommand] (http--0.0.0.0-8080-1) [65960527] Running command: AddVdsCommand internal: false. Entities affected : ID: 8fc43cd8-abe9-4ee7-834b-6416aed91c63 Type: VdsGroupsAction group CREATE_HOST with role type ADMIN >2014-08-10 13:45:13,282 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (http--0.0.0.0-8080-1) [2c3ef0fd] Running command: AddVdsSpmIdCommand internal: true. Entities affected : ID: 7140a009-99a5-4aa6-b769-4299ed51b873 Type: VDS >2014-08-10 13:45:13,289 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (http--0.0.0.0-8080-1) [2c3ef0fd] Lock freed to object EngineLock [exclusiveLocks= key: 76bcbfe3-3419-4fdb-9b89-d147b7fdef99 value: REGISTER_VDS >, sharedLocks= ] >2014-08-10 13:45:13,291 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (http--0.0.0.0-8080-1) [2c3ef0fd] START, RemoveVdsVDSCommand(HostName = h2, HostId = 7140a009-99a5-4aa6-b769-4299ed51b873), log id: 14441339 >2014-08-10 13:45:13,291 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (http--0.0.0.0-8080-1) [2c3ef0fd] FINISH, RemoveVdsVDSCommand, log id: 14441339 >2014-08-10 13:45:13,292 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (http--0.0.0.0-8080-1) [2c3ef0fd] START, AddVdsVDSCommand(HostName = h2, HostId = 7140a009-99a5-4aa6-b769-4299ed51b873), log id: eaa5640 >2014-08-10 13:45:13,293 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (http--0.0.0.0-8080-1) [2c3ef0fd] AddVds - entered , starting logic to add VDS 7140a009-99a5-4aa6-b769-4299ed51b873 >2014-08-10 13:45:13,294 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (http--0.0.0.0-8080-1) [2c3ef0fd] AddVds - VDS 7140a009-99a5-4aa6-b769-4299ed51b873 was added, will try to add it to the resource manager >2014-08-10 13:45:13,295 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (http--0.0.0.0-8080-1) [2c3ef0fd] Entered VdsManager constructor >2014-08-10 13:45:13,301 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (http--0.0.0.0-8080-1) [2c3ef0fd] Initialize vdsBroker (galactica.tlv.redhat.com,54,321) >2014-08-10 13:45:13,301 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (http--0.0.0.0-8080-1) [2c3ef0fd] VDS 7140a009-99a5-4aa6-b769-4299ed51b873 was added to the Resource Manager >2014-08-10 13:45:13,302 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (http--0.0.0.0-8080-1) [2c3ef0fd] FINISH, AddVdsVDSCommand, log id: eaa5640 >2014-08-10 13:45:13,304 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [2c3ef0fd] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host h2. >2014-08-10 13:45:13,330 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [2c3ef0fd] Correlation ID: 65960527, Job ID: 44baca53-7555-49cf-ae02-d16d1719d1cb, Call Stack: null, Custom Event ID: -1, Message: Host h2 was added by admin. >2014-08-10 13:45:13,339 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-22) [73e3795a] Lock Acquired to object EngineLock [exclusiveLocks= key: 7140a009-99a5-4aa6-b769-4299ed51b873 value: VDS >, sharedLocks= ] >2014-08-10 13:45:13,355 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-22) [73e3795a] Running command: InstallVdsInternalCommand internal: true. Entities affected : ID: 7140a009-99a5-4aa6-b769-4299ed51b873 Type: VDS >2014-08-10 13:45:13,356 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-22) [73e3795a] Before Installation host 7140a009-99a5-4aa6-b769-4299ed51b873, h2 >2014-08-10 13:45:13,359 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-22) [73e3795a] START, SetVdsStatusVDSCommand(HostName = h2, HostId = 7140a009-99a5-4aa6-b769-4299ed51b873, status=Installing, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 212e6117 >2014-08-10 13:45:13,372 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-22) [73e3795a] FINISH, SetVdsStatusVDSCommand, log id: 212e6117 >2014-08-10 13:45:13,402 INFO [org.ovirt.engine.core.bll.InstallerMessages] (org.ovirt.thread.pool-8-thread-22) [73e3795a] Installation galactica.tlv.redhat.com: Connected to host galactica.tlv.redhat.com with SSH key fingerprint: af:6b:19:0c:de:1c:76:eb:a5:97:b3:fa:05:62:84:04 >2014-08-10 13:45:13,430 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-22) [73e3795a] Correlation ID: 73e3795a, Call Stack: null, Custom Event ID: -1, Message: Installing Host h2. Connected to host galactica.tlv.redhat.com with SSH key fingerprint: af:6b:19:0c:de:1c:76:eb:a5:97:b3:fa:05:62:84:04. >2014-08-10 13:45:13,482 INFO [org.ovirt.engine.core.bll.VdsDeploy] (org.ovirt.thread.pool-8-thread-22) [73e3795a] Installation of galactica.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 < /home/vvolansk/ovirt-engine/var/cache/ovirt-engine/ovirt-host-deploy.tar >2014-08-10 13:45:13,488 INFO [org.ovirt.engine.core.uutils.ssh.SSHDialog] (org.ovirt.thread.pool-8-thread-22) SSH execute root@galactica.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-08-10 13:45:13,510 ERROR [org.ovirt.engine.core.bll.VdsDeploy] (VdsDeploy) Error during deploy dialog: java.io.IOException: Unexpected connection termination > at org.ovirt.otopi.dialog.MachineDialogParser.nextEvent(MachineDialogParser.java:388) [otopi.jar:] > at org.ovirt.otopi.dialog.MachineDialogParser.nextEvent(MachineDialogParser.java:405) [otopi.jar:] > at org.ovirt.engine.core.bll.VdsDeploy._threadMain(VdsDeploy.java:818) [bll.jar:] > at org.ovirt.engine.core.bll.VdsDeploy.access$2000(VdsDeploy.java:83) [bll.jar:] > at org.ovirt.engine.core.bll.VdsDeploy$51.run(VdsDeploy.java:966) [bll.jar:] > at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25] > >2014-08-10 13:45:13,512 ERROR [org.ovirt.engine.core.uutils.ssh.SSHDialog] (org.ovirt.thread.pool-8-thread-22) Swallowing exception as preferring stderr: java.io.IOException: Command returned failure code 127 during SSH session 'root@galactica.tlv.redhat.com' > at org.ovirt.engine.core.uutils.ssh.SSHClient.executeCommand(SSHClient.java:527) [uutils.jar:] > at org.ovirt.engine.core.uutils.ssh.SSHDialog.executeCommand(SSHDialog.java:318) [uutils.jar:] > at org.ovirt.engine.core.bll.VdsDeploy.execute(VdsDeploy.java:1115) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsInternalCommand.installHost(InstallVdsInternalCommand.java:151) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsInternalCommand.executeCommand(InstallVdsInternalCommand.java:78) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1163) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1290) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1910) [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:1311) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:347) [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_25] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_25] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_25] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_25] > 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:2239) [bll.jar:] > at org.ovirt.engine.core.bll.AddVdsCommand$3.run(AddVdsCommand.java:195) [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_25] > at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) [rt.jar:1.7.0_25] > at java.util.concurrent.FutureTask.run(FutureTask.java:166) [rt.jar:1.7.0_25] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_25] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_25] > at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25] > >2014-08-10 13:45:13,530 ERROR [org.ovirt.engine.core.uutils.ssh.SSHDialog] (org.ovirt.thread.pool-8-thread-22) SSH error running command root@galactica.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': java.lang.RuntimeException: Unexpected error during execution: bash: tar: command not found > > at org.ovirt.engine.core.uutils.ssh.SSHDialog.executeCommand(SSHDialog.java:337) [uutils.jar:] > at org.ovirt.engine.core.bll.VdsDeploy.execute(VdsDeploy.java:1115) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsInternalCommand.installHost(InstallVdsInternalCommand.java:151) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsInternalCommand.executeCommand(InstallVdsInternalCommand.java:78) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1163) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1290) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1910) [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:1311) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:347) [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_25] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_25] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_25] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_25] > 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:2239) [bll.jar:] > at org.ovirt.engine.core.bll.AddVdsCommand$3.run(AddVdsCommand.java:195) [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_25] > at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) [rt.jar:1.7.0_25] > at java.util.concurrent.FutureTask.run(FutureTask.java:166) [rt.jar:1.7.0_25] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_25] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_25] > at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25] > >2014-08-10 13:45:13,549 ERROR [org.ovirt.engine.core.bll.VdsDeploy] (org.ovirt.thread.pool-8-thread-22) [73e3795a] Error during host galactica.tlv.redhat.com install: java.lang.RuntimeException: Unexpected error during execution: bash: tar: command not found > > at org.ovirt.engine.core.uutils.ssh.SSHDialog.executeCommand(SSHDialog.java:337) [uutils.jar:] > at org.ovirt.engine.core.bll.VdsDeploy.execute(VdsDeploy.java:1115) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsInternalCommand.installHost(InstallVdsInternalCommand.java:151) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsInternalCommand.executeCommand(InstallVdsInternalCommand.java:78) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1163) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1290) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1910) [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:1311) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:347) [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_25] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_25] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_25] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_25] > 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:2239) [bll.jar:] > at org.ovirt.engine.core.bll.AddVdsCommand$3.run(AddVdsCommand.java:195) [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_25] > at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) [rt.jar:1.7.0_25] > at java.util.concurrent.FutureTask.run(FutureTask.java:166) [rt.jar:1.7.0_25] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_25] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_25] > at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25] > >2014-08-10 13:45:13,567 ERROR [org.ovirt.engine.core.bll.InstallerMessages] (org.ovirt.thread.pool-8-thread-22) [73e3795a] Installation galactica.tlv.redhat.com: Unexpected error during execution: bash: tar: command not found > >2014-08-10 13:45:13,579 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-22) [73e3795a] Correlation ID: 73e3795a, Call Stack: null, Custom Event ID: -1, Message: Failed to install Host h2. Unexpected error during execution: bash: tar: command not found >. >2014-08-10 13:45:13,579 ERROR [org.ovirt.engine.core.bll.VdsDeploy] (org.ovirt.thread.pool-8-thread-22) [73e3795a] Error during host galactica.tlv.redhat.com install, prefering first exception: java.io.IOException: Unexpected connection termination > at org.ovirt.otopi.dialog.MachineDialogParser.nextEvent(MachineDialogParser.java:388) [otopi.jar:] > at org.ovirt.otopi.dialog.MachineDialogParser.nextEvent(MachineDialogParser.java:405) [otopi.jar:] > at org.ovirt.engine.core.bll.VdsDeploy._threadMain(VdsDeploy.java:818) [bll.jar:] > at org.ovirt.engine.core.bll.VdsDeploy.access$2000(VdsDeploy.java:83) [bll.jar:] > at org.ovirt.engine.core.bll.VdsDeploy$51.run(VdsDeploy.java:966) [bll.jar:] > at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25] > >2014-08-10 13:45:13,581 ERROR [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-22) [73e3795a] Host installation failed for host 7140a009-99a5-4aa6-b769-4299ed51b873, h2.: java.io.IOException: Unexpected connection termination > at org.ovirt.otopi.dialog.MachineDialogParser.nextEvent(MachineDialogParser.java:388) [otopi.jar:] > at org.ovirt.otopi.dialog.MachineDialogParser.nextEvent(MachineDialogParser.java:405) [otopi.jar:] > at org.ovirt.engine.core.bll.VdsDeploy._threadMain(VdsDeploy.java:818) [bll.jar:] > at org.ovirt.engine.core.bll.VdsDeploy.access$2000(VdsDeploy.java:83) [bll.jar:] > at org.ovirt.engine.core.bll.VdsDeploy$51.run(VdsDeploy.java:966) [bll.jar:] > at java.lang.Thread.run(Thread.java:724) [rt.jar:1.7.0_25] > >2014-08-10 13:45:13,583 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-22) [73e3795a] START, SetVdsStatusVDSCommand(HostName = h2, HostId = 7140a009-99a5-4aa6-b769-4299ed51b873, status=InstallFailed, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 1ab334c1 >2014-08-10 13:45:13,595 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-22) [73e3795a] FINISH, SetVdsStatusVDSCommand, log id: 1ab334c1 >2014-08-10 13:45:13,603 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-22) [73e3795a] Correlation ID: 73e3795a, Job ID: 44baca53-7555-49cf-ae02-d16d1719d1cb, Call Stack: null, Custom Event ID: -1, Message: Host h2 installation failed. Unexpected connection termination. >2014-08-10 13:45:13,612 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-22) [73e3795a] Lock freed to object EngineLock [exclusiveLocks= key: 7140a009-99a5-4aa6-b769-4299ed51b873 value: VDS >, sharedLocks= ] >2014-08-10 13:45:16,303 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-66) Initializing Host: h2 >2014-08-10 13:46:04,876 INFO [org.ovirt.engine.core.bll.RemoveVdsCommand] (http--0.0.0.0-8080-1) [11b8aae7] Lock Acquired to object EngineLock [exclusiveLocks= key: 7140a009-99a5-4aa6-b769-4299ed51b873 value: VDS >, sharedLocks= ] >2014-08-10 13:46:04,881 INFO [org.ovirt.engine.core.bll.RemoveVdsCommand] (org.ovirt.thread.pool-8-thread-34) [11b8aae7] Running command: RemoveVdsCommand internal: false. Entities affected : ID: 7140a009-99a5-4aa6-b769-4299ed51b873 Type: VDSAction group DELETE_HOST with role type ADMIN >2014-08-10 13:46:04,993 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (org.ovirt.thread.pool-8-thread-34) [11b8aae7] START, RemoveVdsVDSCommand( HostId = 7140a009-99a5-4aa6-b769-4299ed51b873), log id: 3ad35eef >2014-08-10 13:46:04,993 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (org.ovirt.thread.pool-8-thread-34) [11b8aae7] vdsManager::disposing >2014-08-10 13:46:04,994 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (org.ovirt.thread.pool-8-thread-34) [11b8aae7] FINISH, RemoveVdsVDSCommand, log id: 3ad35eef >2014-08-10 13:46:05,000 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-34) [11b8aae7] Correlation ID: 11b8aae7, Call Stack: null, Custom Event ID: -1, Message: Host h2 was removed by admin. >2014-08-10 13:46:05,001 INFO [org.ovirt.engine.core.bll.RemoveVdsCommand] (org.ovirt.thread.pool-8-thread-34) [11b8aae7] Lock freed to object EngineLock [exclusiveLocks= key: 7140a009-99a5-4aa6-b769-4299ed51b873 value: VDS >, sharedLocks= ]
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 1128417
:
925486
| 925487 |
925636
|
925824
|
925836
|
925837
|
925838
|
925969
|
925970
|
925973
|
925974
|
925975