Attachment 863678 Details for Bug 1065719 – engien log

engien log

engine.log (text/x-log), 62.88 KB, created by Maor on 2014-02-16 09:44:49 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Maor

Created: 2014-02-16 09:44:49 UTC

Size: 62.88 KB

patch

obsolete

>2014-02-16 11:41:47,401 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Loaded file "/home/mlipchuk/ovirt-share/share/ovirt-engine/services/ovirt-engine/ovirt-engine.conf".
>2014-02-16 11:41:47,403 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Loaded file "/home/mlipchuk/ovirt-share/etc/ovirt-engine/engine.conf".
>2014-02-16 11:41:47,404 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Loaded file "/home/mlipchuk/ovirt-share/etc/ovirt-engine/engine.conf.d/10-setup-database.conf".
>2014-02-16 11:41:47,405 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Loaded file "/home/mlipchuk/ovirt-share/etc/ovirt-engine/engine.conf.d/10-setup-java.conf".
>2014-02-16 11:41:47,406 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Loaded file "/home/mlipchuk/ovirt-share/etc/ovirt-engine/engine.conf.d/10-setup-jboss.conf".
>2014-02-16 11:41:47,407 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Loaded file "/home/mlipchuk/ovirt-share/etc/ovirt-engine/engine.conf.d/10-setup-pki.conf".
>2014-02-16 11:41:47,409 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Loaded file "/home/mlipchuk/ovirt-share/etc/ovirt-engine/engine.conf.d/10-setup-protocols.conf".
>2014-02-16 11:41:47,410 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_AJP_ENABLED" is "false".
>2014-02-16 11:41:47,411 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_AJP_PORT" is "8702".
>2014-02-16 11:41:47,412 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_APPS" is "engine.ear".
>2014-02-16 11:41:47,412 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_CACHE" is "/home/mlipchuk/ovirt-share/var/cache/ovirt-engine".
>2014-02-16 11:41:47,413 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_DB_CHECK_INTERVAL" is "1000".
>2014-02-16 11:41:47,414 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_DB_CONNECTION_TIMEOUT" is "300000".
>2014-02-16 11:41:47,414 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_DB_DATABASE" is "engine".
>2014-02-16 11:41:47,415 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_DB_DRIVER" is "org.postgresql.Driver".
>2014-02-16 11:41:47,415 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_DB_HOST" is "localhost".
>2014-02-16 11:41:47,416 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_DB_MAX_CONNECTIONS" is "100".
>2014-02-16 11:41:47,417 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_DB_MIN_CONNECTIONS" is "1".
>2014-02-16 11:41:47,417 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_DB_PASSWORD" is "***".
>2014-02-16 11:41:47,418 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_DB_PORT" is "5432".
>2014-02-16 11:41:47,419 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_DB_SECURED" is "False".
>2014-02-16 11:41:47,419 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_DB_SECURED_VALIDATION" is "False".
>2014-02-16 11:41:47,420 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_DB_URL" is "jdbc:postgresql://localhost:5432/engine?&sslfactory=org.postgresql.ssl.NonValidatingFactory".
>2014-02-16 11:41:47,421 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_DB_USER" is "postgres".
>2014-02-16 11:41:47,422 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_DEBUG_ADDRESS" is "127.0.0.1:8787".
>2014-02-16 11:41:47,422 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_DOC" is "/home/mlipchuk/ovirt-share/share/doc/ovirt-engine".
>2014-02-16 11:41:47,423 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_ETC" is "/home/mlipchuk/ovirt-share/etc/ovirt-engine".
>2014-02-16 11:41:47,424 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_FQDN" is "dhcp-1-142.tlv.redhat.com".
>2014-02-16 11:41:47,424 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_GROUP" is "ovirt".
>2014-02-16 11:41:47,425 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_HEAP_MAX" is "1g".
>2014-02-16 11:41:47,425 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_HEAP_MIN" is "1g".
>2014-02-16 11:41:47,426 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_HTTPS_ENABLED" is "true".
>2014-02-16 11:41:47,426 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_HTTPS_PORT" is "8443".
>2014-02-16 11:41:47,427 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_HTTPS_PROTOCOLS" is "SSLv3,TLSv1,TLSv1.1,TLSv1.2".
>2014-02-16 11:41:47,428 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_HTTP_ENABLED" is "true".
>2014-02-16 11:41:47,428 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_HTTP_PORT" is "8080".
>2014-02-16 11:41:47,429 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_JAVA_MODULEPATH" is "/home/mlipchuk/ovirt-share/share/ovirt-engine/modules".
>2014-02-16 11:41:47,430 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_JVM_ARGS" is " -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath="/home/mlipchuk/ovirt-share/var/log/ovirt-engine/dump"".
>2014-02-16 11:41:47,430 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_LOG" is "/home/mlipchuk/ovirt-share/var/log/ovirt-engine".
>2014-02-16 11:41:47,431 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_LOG_TO_CONSOLE" is "true".
>2014-02-16 11:41:47,432 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_MANUAL" is "/home/mlipchuk/ovirt-share/share/ovirt-engine/manual".
>2014-02-16 11:41:47,432 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_PERM_MAX" is "256m".
>2014-02-16 11:41:47,434 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_PERM_MIN" is "256m".
>2014-02-16 11:41:47,435 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_PKI" is "/home/mlipchuk/ovirt-share/etc/pki/ovirt-engine".
>2014-02-16 11:41:47,436 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_PKI_CA" is "/home/mlipchuk/ovirt-share/etc/pki/ovirt-engine/ca.pem".
>2014-02-16 11:41:47,437 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_PKI_ENGINE_CERT" is "/home/mlipchuk/ovirt-share/etc/pki/ovirt-engine/certs/engine.cer".
>2014-02-16 11:41:47,443 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_PKI_ENGINE_STORE" is "/home/mlipchuk/ovirt-share/etc/pki/ovirt-engine/keys/engine.p12".
>2014-02-16 11:41:47,444 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_PKI_ENGINE_STORE_ALIAS" is "1".
>2014-02-16 11:41:47,445 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_PKI_ENGINE_STORE_PASSWORD" is "***".
>2014-02-16 11:41:47,446 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_PKI_TRUST_STORE" is "/home/mlipchuk/ovirt-share/etc/pki/ovirt-engine/.truststore".
>2014-02-16 11:41:47,446 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_PKI_TRUST_STORE_PASSWORD" is "***".
>2014-02-16 11:41:47,447 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_PROPERTIES" is " jsse.enableSNIExtension=false".
>2014-02-16 11:41:47,452 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_PROXY_ENABLED" is "false".
>2014-02-16 11:41:47,453 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_PROXY_HTTPS_PORT" is "443".
>2014-02-16 11:41:47,453 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_PROXY_HTTP_PORT" is "80".
>2014-02-16 11:41:47,454 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_REPORTS_UI" is "/home/mlipchuk/ovirt-share/var/lib/ovirt-engine/reports.xml".
>2014-02-16 11:41:47,455 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_STOP_INTERVAL" is "1".
>2014-02-16 11:41:47,459 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_STOP_TIME" is "10".
>2014-02-16 11:41:47,460 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_TMP" is "/home/mlipchuk/ovirt-share/var/tmp/ovirt-engine".
>2014-02-16 11:41:47,461 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_UP_MARK" is "/home/mlipchuk/ovirt-share/var/lib/ovirt-engine/engine.up".
>2014-02-16 11:41:47,462 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_URI" is "/ovirt-engine".
>2014-02-16 11:41:47,463 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_USER" is "ovirt".
>2014-02-16 11:41:47,463 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_USR" is "/home/mlipchuk/ovirt-share/share/ovirt-engine".
>2014-02-16 11:41:47,474 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_VAR" is "/home/mlipchuk/ovirt-share/var/lib/ovirt-engine".
>2014-02-16 11:41:47,475 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "ENGINE_VERBOSE_GC" is "false".
>2014-02-16 11:41:47,476 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "JAVA_HOME" is "/usr/lib/jvm/jre-1.7.0-openjdk.x86_64".
>2014-02-16 11:41:47,476 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "JBOSS_HOME" is "/usr/share/jboss-as".
>2014-02-16 11:41:47,477 INFO  [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-5) Value of property "SENSITIVE_KEYS" is ",ENGINE_DB_PASSWORD,ENGINE_PKI_TRUST_STORE_PASSWORD,ENGINE_PKI_ENGINE_STORE_PASSWORD".
>2014-02-16 11:41:48,236 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) Start initializing Backend
>2014-02-16 11:41:48,424 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_45]
>	at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:272) [rt.jar:1.7.0_45]
>	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_45]
>	at org.ovirt.engine.core.utils.crypt.EngineEncryptionUtils.decrypt(EngineEncryptionUtils.java:158) [utils.jar:]
>	at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.getValue(DBConfigUtils.java:107) [dal.jar:]
>	at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.updateOption(DBConfigUtils.java:258) [dal.jar:]
>	at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.refreshVdcOptionCache(DBConfigUtils.java:35) [dal.jar:]
>	at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.<init>(DBConfigUtils.java:166) [dal.jar:]
>	at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.<init>(DBConfigUtils.java:152) [dal.jar:]
>	at org.ovirt.engine.core.bll.Backend.initialize(Backend.java:182) [bll.jar:]
>	at org.ovirt.engine.core.bll.Backend.create(Backend.java:129) [bll.jar:]
>	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_45]
>	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_45]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_45]
>	at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_45]
>	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.jar:1.1.1.Final]
>	at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation.jar:1.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.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.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.jar:1.1.1.Final]
>	at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation.jar:1.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.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.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.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.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.jar:1.1.1.Final]
>	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.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_45]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_45]
>	at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_45]
>
>2014-02-16 11:41:48,450 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: HotPlugCpuEnabled
>2014-02-16 11:41:48,451 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: HotPlugCpuEnabled
>2014-02-16 11:41:48,452 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: HotPlugCpuEnabled
>2014-02-16 11:41:48,453 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: HotPlugCpuEnabled
>2014-02-16 11:41:48,453 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: HotPlugCpuEnabled
>2014-02-16 11:41:48,518 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_45]
>	at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:272) [rt.jar:1.7.0_45]
>	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_45]
>	at org.ovirt.engine.core.utils.crypt.EngineEncryptionUtils.decrypt(EngineEncryptionUtils.java:158) [utils.jar:]
>	at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.getValue(DBConfigUtils.java:107) [dal.jar:]
>	at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.updateOption(DBConfigUtils.java:258) [dal.jar:]
>	at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.refreshVdcOptionCache(DBConfigUtils.java:35) [dal.jar:]
>	at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.<init>(DBConfigUtils.java:166) [dal.jar:]
>	at org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils.<init>(DBConfigUtils.java:152) [dal.jar:]
>	at org.ovirt.engine.core.bll.Backend.initialize(Backend.java:182) [bll.jar:]
>	at org.ovirt.engine.core.bll.Backend.create(Backend.java:129) [bll.jar:]
>	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_45]
>	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_45]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_45]
>	at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_45]
>	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.jar:1.1.1.Final]
>	at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation.jar:1.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.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.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.jar:1.1.1.Final]
>	at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation.jar:1.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.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.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.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.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.jar:1.1.1.Final]
>	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.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_45]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_45]
>	at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_45]
>
>2014-02-16 11:41:48,627 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-1) Could not parse option AutoRecoveryAllowedTypes value.
>2014-02-16 11:41:48,630 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-1) Could not find enum value for option: ChangePasswordUrl
>2014-02-16 11:41:48,633 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) Running ovirt-engine 3.4.0.0
>2014-02-16 11:41:48,637 INFO  [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-1) Start initializing dictionaries
>2014-02-16 11:41:48,644 INFO  [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-1) Finished initializing dictionaries
>2014-02-16 11:41:48,645 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Start initializing AuditLogCleanupManager
>2014-02-16 11:41:48,647 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Setting audit cleanup manager to run at: 35 35 3 * * ?
>2014-02-16 11:41:48,676 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Finished initializing AuditLogCleanupManager
>2014-02-16 11:41:48,681 INFO  [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-1) Start initializing TagsDirector
>2014-02-16 11:41:48,700 INFO  [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-1) Tag root added to tree
>2014-02-16 11:41:48,717 INFO  [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-1) Finished initializing TagsDirector
>2014-02-16 11:41:48,721 INFO  [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-1) Start initializing IsoDomainListSyncronizer
>2014-02-16 11:41:48,728 INFO  [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-1) Finished initializing IsoDomainListSyncronizer
>2014-02-16 11:41:48,743 INFO  [org.ovirt.engine.core.utils.osinfo.OsInfoPreferencesLoader] (MSC service thread 1-1) Loaded file /home/mlipchuk/ovirt-share/etc/ovirt-engine/osinfo.conf.d/00-defaults.properties
>2014-02-16 11:41:48,988 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) Completed initializing handlers
>2014-02-16 11:41:48,990 INFO  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-1) Start initializing ErrorTranslatorImpl
>2014-02-16 11:41:49,007 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-02-16 11:41:49,010 INFO  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-1) Finished initializing ErrorTranslatorImpl
>2014-02-16 11:41:49,012 INFO  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-1) Start initializing ErrorTranslatorImpl
>2014-02-16 11:41:49,013 INFO  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-1) Finished initializing ErrorTranslatorImpl
>2014-02-16 11:41:49,014 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) Mark incomplete jobs as UNKNOWN
>2014-02-16 11:41:49,077 INFO  [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-1) Start initializing JobRepositoryCleanupManager
>2014-02-16 11:41:49,079 INFO  [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-1) Finished initializing JobRepositoryCleanupManager
>2014-02-16 11:41:49,080 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-1) Start initializing AutoRecoveryManager
>2014-02-16 11:41:49,082 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-1) Finished initializing AutoRecoveryManager
>2014-02-16 11:41:49,085 INFO  [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-1) Start initializing ExecutionMessageDirector
>2014-02-16 11:41:49,088 INFO  [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-1) Finished initializing ExecutionMessageDirector
>2014-02-16 11:41:49,147 INFO  [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-4) Start initializing UsersDomainsCacheManagerService
>2014-02-16 11:41:49,164 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (MSC service thread 1-4) Start initializing DbUserCacheManager
>2014-02-16 11:41:49,166 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (MSC service thread 1-4) Finished initializing DbUserCacheManager
>2014-02-16 11:41:49,168 INFO  [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-4) Finished initializing UsersDomainsCacheManagerService
>2014-02-16 11:41:49,189 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (MSC service thread 1-5) Initialization of AsyncTaskManager completed successfully.
>2014-02-16 11:41:49,196 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-5) Start initializing ResourceManager
>2014-02-16 11:41:49,349 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-5) Entered VdsManager constructor
>2014-02-16 11:41:49,368 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-5) Initialize vdsBroker (pluto-vdsb.eng.lab.tlv.redhat.com,54,321)
>2014-02-16 11:41:49,468 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-5) VDS 142ca4cc-82df-4d9a-8d7e-5741b77fada3 was added to the Resource Manager
>2014-02-16 11:41:49,469 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-5) Entered VdsManager constructor
>2014-02-16 11:41:49,473 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-5) Initialize vdsBroker (10.35.3.84,54,321)
>2014-02-16 11:41:49,480 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-5) VDS 95070901-19ea-4f27-9852-bb106cb88cb3 was added to the Resource Manager
>2014-02-16 11:41:49,504 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-5) Finished initializing ResourceManager
>2014-02-16 11:41:49,511 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (MSC service thread 1-5) Initialization of OvfDataUpdater completed successfully.
>2014-02-16 11:41:49,515 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-5) Start scheduling to enable vds load balancer
>2014-02-16 11:41:49,517 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-5) Finished scheduling to enable vds load balancer
>2014-02-16 11:41:49,517 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-5) Start HA Reservation check
>2014-02-16 11:41:49,518 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-5) Finished HA Reservation check
>2014-02-16 11:41:49,529 INFO  [org.ovirt.engine.core.bll.network.MacPoolManager] (org.ovirt.thread.pool-6-thread-1) MacPoolManager(69dce920): Start initializing
>2014-02-16 11:41:49,546 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-5) Init VM custom properties utilities
>2014-02-16 11:41:49,548 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-5) Init device custom properties utilities
>2014-02-16 11:41:49,553 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-5) Initializing Scheduling manager
>2014-02-16 11:41:49,556 INFO  [org.ovirt.engine.core.bll.network.MacPoolManager] (org.ovirt.thread.pool-6-thread-1) MacPoolManager(69dce920): Finished initializing. Available MACs in pool: 145
>2014-02-16 11:41:49,596 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-5) External scheduler disabled, discovery skipped
>2014-02-16 11:41:49,598 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-5) Initialized Scheduling manager
>2014-02-16 11:41:49,599 INFO  [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-5) Initializing DWH Heart Beat
>2014-02-16 11:41:49,602 INFO  [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-5) DWH Heart Beat initialized
>2014-02-16 11:41:52,970 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-7) START, GetHardwareInfoVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vds=Host[venus-vdsa]), log id: 3e5b221
>2014-02-16 11:41:52,972 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: UNASSIGNED not exist in string table
>2014-02-16 11:41:52,974 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_FAILED_REMOVE_VM not exist in string table
>2014-02-16 11:41:52,975 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_RUN_UNLOCK_ENTITY_SCRIPT not exist in string table
>2014-02-16 11:41:52,976 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-02-16 11:41:52,978 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: STORAGE_ACTIVATE_ASYNC not exist in string table
>2014-02-16 11:41:52,978 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: DWH_STOPPED not exist in string table
>2014-02-16 11:41:52,979 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: DWH_STARTED not exist in string table
>2014-02-16 11:41:52,980 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: DWH_ERROR not exist in string table
>2014-02-16 11:41:52,989 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-7) FINISH, GetHardwareInfoVDSCommand, log id: 3e5b221
>2014-02-16 11:41:52,994 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_FAILED_REMOVE_VM not have severity. Assumed Normal
>2014-02-16 11:41:52,999 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_ATTACH_DISK_TO_VM not have severity. Assumed Normal
>2014-02-16 11:41:53,001 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_DETACH_DISK_FROM_VM not have severity. Assumed Normal
>2014-02-16 11:41:53,004 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_FAILED_DETACH_DISK_FROM_VM not have severity. Assumed Normal
>2014-02-16 11:41:53,005 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_RUN_UNLOCK_ENTITY_SCRIPT not have severity. Assumed Normal
>2014-02-16 11:41:53,008 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: GLUSTER_VOLUME_OPTION_CHANGED_FROM_CLI not have severity. Assumed Normal
>2014-02-16 11:41:53,011 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: GLUSTER_SERVICES_LIST_NOT_FETCHED not have severity. Assumed Normal
>2014-02-16 11:41:53,015 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: GLUSTER_VOLUME_BRICK_ADDED not have severity. Assumed Normal
>2014-02-16 11:41:53,016 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_EXTEND_DISK_SIZE_UPDATE_VM_FAILURE not have severity. Assumed Normal
>2014-02-16 11:41:53,017 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: VM_MIGRATION_START_SYSTEM_INITIATED not have severity. Assumed Normal
>2014-02-16 11:41:53,019 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: VDS_NETWORK_MTU_DIFFER_FROM_LOGICAL_NETWORK not have severity. Assumed Normal
>2014-02-16 11:41:53,020 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: NETWORK_UPDATE_VM_INTERFACE_LINK_UP not have severity. Assumed Normal
>2014-02-16 11:41:53,021 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: NETWORK_UPDATE_VM_INTERFACE_LINK_DOWN not have severity. Assumed Normal
>2014-02-16 11:41:53,023 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_ADDED_AFFINITY_GROUP not have severity. Assumed Normal
>2014-02-16 11:41:53,024 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_FAILED_TO_ADD_AFFINITY_GROUP not have severity. Assumed Normal
>2014-02-16 11:41:53,034 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_UPDATED_AFFINITY_GROUP not have severity. Assumed Normal
>2014-02-16 11:41:53,035 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_FAILED_TO_UPDATE_AFFINITY_GROUP not have severity. Assumed Normal
>2014-02-16 11:41:53,036 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_REMOVED_AFFINITY_GROUP not have severity. Assumed Normal
>2014-02-16 11:41:53,037 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) AuditLogType: USER_FAILED_TO_REMOVE_AFFINITY_GROUP not have severity. Assumed Normal
>2014-02-16 11:41:53,104 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Network ovirtmgmt is not attached to any interface on host pluto-vdsb.eng.lab.tlv.redhat.com.
>2014-02-16 11:41:53,106 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-6) START, GetHardwareInfoVDSCommand(HostName = pluto-vdsb.eng.lab.tlv.redhat.com, HostId = 142ca4cc-82df-4d9a-8d7e-5741b77fada3, vds=Host[pluto-vdsb.eng.lab.tlv.redhat.com]), log id: 7ecfcdae
>2014-02-16 11:41:53,112 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-7) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: State was set to Up for host venus-vdsa.
>2014-02-16 11:41:53,118 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-6) FINISH, GetHardwareInfoVDSCommand, log id: 7ecfcdae
>2014-02-16 11:41:53,220 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: State was set to Up for host pluto-vdsb.eng.lab.tlv.redhat.com.
>2014-02-16 11:41:53,341 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-6) Initializing Host: pluto-vdsb.eng.lab.tlv.redhat.com
>2014-02-16 11:41:53,346 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-7) Initializing Host: venus-vdsa
>2014-02-16 11:41:53,488 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (DefaultQuartzScheduler_Worker-7) [7d6d9d3a] Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: 95070901-19ea-4f27-9852-bb106cb88cb3 Type: VDS
>2014-02-16 11:41:53,488 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (DefaultQuartzScheduler_Worker-6) [1665ec12] Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: 142ca4cc-82df-4d9a-8d7e-5741b77fada3 Type: VDS
>2014-02-16 11:41:59,556 INFO  [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (DefaultQuartzScheduler_Worker-20) [3a7e69ab] Running command: SetStoragePoolStatusCommand internal: true. Entities affected :  ID: af2c9167-5d67-4494-ae09-def33cf383c2 Type: StoragePool
>2014-02-16 11:41:59,971 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-20) [3a7e69ab] Correlation ID: 3a7e69ab, Call Stack: null, Custom Event ID: -1, Message: Invalid status on Data Center DC33. Setting status to Non Responsive.
>2014-02-16 11:42:00,007 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (DefaultQuartzScheduler_Worker-20) [3a7e69ab] hostFromVds::selectedVds - venus-vdsa, spmStatus SPM, storage pool DC33
>2014-02-16 11:42:00,022 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (DefaultQuartzScheduler_Worker-20) [3a7e69ab] Initialize Irs proxy from vds: 10.35.3.84
>2014-02-16 11:42:00,055 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-20) [3a7e69ab] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Storage Pool Manager runs on Host venus-vdsa (Address: 10.35.3.84).
>2014-02-16 11:42:00,117 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-6-thread-17) [3a7e69ab] START, SPMGetAllTasksInfoVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false), log id: 792143a6
>2014-02-16 11:42:00,185 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-6-thread-17) [3a7e69ab] -- executeIrsBrokerCommand: Attempting on storage pool af2c9167-5d67-4494-ae09-def33cf383c2
>2014-02-16 11:42:00,203 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-6-thread-17) [3a7e69ab] START, HSMGetAllTasksInfoVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3), log id: 18bfc8ed
>2014-02-16 11:42:00,220 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-6-thread-17) [3a7e69ab] FINISH, HSMGetAllTasksInfoVDSCommand, return: [], log id: 18bfc8ed
>2014-02-16 11:42:00,221 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-6-thread-17) [3a7e69ab] FINISH, SPMGetAllTasksInfoVDSCommand, return: [], log id: 792143a6
>2014-02-16 11:42:00,222 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (org.ovirt.thread.pool-6-thread-17) [3a7e69ab] Discovered no tasks on Storage Pool DC33
>2014-02-16 11:42:08,447 INFO  [org.ovirt.engine.core.bll.LoginAdminUserCommand] (http--0.0.0.0-8080-1) Running command: LoginAdminUserCommand internal: false.
>2014-02-16 11:42:08,490 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-02-16 11:42:09,209 ERROR [org.ovirt.engine.core.utils.servlet.ServletUtils] (http--0.0.0.0-8080-1) Can't read file "/home/mlipchuk/ovirt-share/var/lib/ovirt-engine/reports.xml" for request "/ovirt-engine/services/reports-ui", will send a 404 error response.
>2014-02-16 11:42:09,673 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (http--0.0.0.0-8080-2) Running command: LoginUserCommand internal: false.
>2014-02-16 11:42:36,152 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (http--0.0.0.0-8080-1) [6863c4cf] Lock Acquired to object EngineLock [exclusiveLocks= key: 3479c888-98f3-4d71-ad3b-b91f6082d889 value: VM
>, sharedLocks= ]
>2014-02-16 11:42:36,269 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-6-thread-8) [6863c4cf] Running command: ShutdownVmCommand internal: false. Entities affected :  ID: 3479c888-98f3-4d71-ad3b-b91f6082d889 Type: VM
>2014-02-16 11:42:36,280 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-6-thread-8) [6863c4cf] Entered (VM VMToTest).
>2014-02-16 11:42:36,282 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-6-thread-8) [6863c4cf] Sending shutdown command for VM VMToTest.
>2014-02-16 11:42:36,322 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-6-thread-8) [6863c4cf] START, DestroyVmVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=3479c888-98f3-4d71-ad3b-b91f6082d889, force=false, secondsToWait=30, gracefully=true), log id: 693651d5
>2014-02-16 11:42:36,567 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-6-thread-8) [6863c4cf] START, DestroyVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=3479c888-98f3-4d71-ad3b-b91f6082d889, force=false, secondsToWait=30, gracefully=true), log id: 6781bc1a
>2014-02-16 11:42:36,578 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-6-thread-8) [6863c4cf] FINISH, DestroyVDSCommand, log id: 6781bc1a
>2014-02-16 11:42:36,636 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-6-thread-8) [6863c4cf] FINISH, DestroyVmVDSCommand, return: PoweringDown, log id: 693651d5
>2014-02-16 11:42:36,703 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-8) [6863c4cf] Correlation ID: 6863c4cf, Job ID: 9a8e7d67-e791-42eb-b738-c1033fde96b7, Call Stack: null, Custom Event ID: -1, Message: VM shutdown initiated by admin on VM VMToTest (Host: venus-vdsa).
>2014-02-16 11:42:36,728 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-6-thread-8) [6863c4cf] Lock freed to object EngineLock [exclusiveLocks= key: 3479c888-98f3-4d71-ad3b-b91f6082d889 value: VM
>, sharedLocks= ]
>2014-02-16 11:42:39,789 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (http--0.0.0.0-8080-1) [6697167f] Lock Acquired to object EngineLock [exclusiveLocks= key: 3479c888-98f3-4d71-ad3b-b91f6082d889 value: VM
>, sharedLocks= ]
>2014-02-16 11:42:39,879 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-6-thread-19) [6697167f] Running command: ShutdownVmCommand internal: false. Entities affected :  ID: 3479c888-98f3-4d71-ad3b-b91f6082d889 Type: VM
>2014-02-16 11:42:39,899 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-6-thread-19) [6697167f] Entered (VM VMToTest).
>2014-02-16 11:42:39,900 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-6-thread-19) [6697167f] Cannot shutdown VM VMToTest, status is not up. Stopping instead.
>2014-02-16 11:42:39,942 INFO  [org.ovirt.engine.core.bll.StopVmCommand] (org.ovirt.thread.pool-6-thread-19) [7696aacb] Running command: StopVmCommand internal: true. Entities affected :  ID: 3479c888-98f3-4d71-ad3b-b91f6082d889 Type: VM
>2014-02-16 11:42:39,949 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-6-thread-19) [7696aacb] START, DestroyVmVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=3479c888-98f3-4d71-ad3b-b91f6082d889, force=false, secondsToWait=0, gracefully=false), log id: 1d89ed82
>2014-02-16 11:42:39,986 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-6-thread-19) [7696aacb] START, DestroyVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=3479c888-98f3-4d71-ad3b-b91f6082d889, force=false, secondsToWait=0, gracefully=false), log id: 456a3907
>2014-02-16 11:42:40,535 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-6-thread-19) [7696aacb] FINISH, DestroyVDSCommand, log id: 456a3907
>2014-02-16 11:42:40,663 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-6-thread-19) [7696aacb] FINISH, DestroyVmVDSCommand, return: Down, log id: 1d89ed82
>2014-02-16 11:42:40,687 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-19) [7696aacb] Correlation ID: 7696aacb, Call Stack: null, Custom Event ID: -1, Message: VM VMToTest was powered off ungracefully by admin (Host: venus-vdsa).
>2014-02-16 11:42:40,689 INFO  [org.ovirt.engine.core.bll.StopVmCommand] (org.ovirt.thread.pool-6-thread-19) [7696aacb] Lock freed to object EngineLock [exclusiveLocks= key: 3479c888-98f3-4d71-ad3b-b91f6082d889 value: VM
>, sharedLocks= ]
>2014-02-16 11:42:40,704 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (org.ovirt.thread.pool-6-thread-19) [7696aacb] Trying to release exclusive lock which does not exist, lock key: 3479c888-98f3-4d71-ad3b-b91f6082d889VM
>2014-02-16 11:42:40,706 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-6-thread-19) [7696aacb] Lock freed to object EngineLock [exclusiveLocks= key: 3479c888-98f3-4d71-ad3b-b91f6082d889 value: VM
>, sharedLocks= ]
>2014-02-16 11:42:57,940 WARN  [org.ovirt.engine.core.bll.RunVmOnceCommand] (http--0.0.0.0-8080-1) [1a5cb10a] CanDoAction of action RunVmOnce failed. Reasons:VAR__ACTION__RUN,VAR__TYPE__VM,VM_CANNOT_RUN_FROM_CD_WITHOUT_ACTIVE_STORAGE_DOMAIN_ISO
>2014-02-16 11:43:11,673 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [cdfa358] Lock Acquired to object EngineLock [exclusiveLocks= key: 0bff6863-2bdc-45da-8bcb-8349537f53ff value: STORAGE
>, sharedLocks= ]
>2014-02-16 11:43:11,841 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (org.ovirt.thread.pool-6-thread-7) [cdfa358] Running command: ActivateStorageDomainCommand internal: false. Entities affected :  ID: 0bff6863-2bdc-45da-8bcb-8349537f53ff Type: Storage
>2014-02-16 11:43:11,892 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (org.ovirt.thread.pool-6-thread-7) [cdfa358] Lock freed to object EngineLock [exclusiveLocks= key: 0bff6863-2bdc-45da-8bcb-8349537f53ff value: STORAGE
>, sharedLocks= ]
>2014-02-16 11:43:11,893 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (org.ovirt.thread.pool-6-thread-7) [cdfa358] ActivateStorage Domain. Before Connect all hosts to pool. Time:2/16/14 11:43 AM
>2014-02-16 11:43:11,920 INFO  [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (org.ovirt.thread.pool-6-thread-9) [65028b0a] Running command: ConnectStorageToVdsCommand internal: true. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2014-02-16 11:43:11,928 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-6-thread-9) [65028b0a] START, ConnectStorageServerVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 6c7ada91-de33-447e-8242-bf738a8d7738, connection: multipass.eng.lab.tlv.redhat.com:/export/images/rnd/maor/iso_ovirt, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 1ade9988
>2014-02-16 11:43:13,317 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-6-thread-9) [65028b0a] FINISH, ConnectStorageServerVDSCommand, return: {6c7ada91-de33-447e-8242-bf738a8d7738=0}, log id: 1ade9988
>2014-02-16 11:43:13,323 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (org.ovirt.thread.pool-6-thread-7) [cdfa358] START, ActivateStorageDomainVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false, storageDomainId = 0bff6863-2bdc-45da-8bcb-8349537f53ff), log id: 60d62994
>2014-02-16 11:43:14,107 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (org.ovirt.thread.pool-6-thread-7) [cdfa358] FINISH, ActivateStorageDomainVDSCommand, log id: 60d62994
>2014-02-16 11:43:14,108 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (org.ovirt.thread.pool-6-thread-7) [cdfa358] ActivateStorage Domain. After Connect all hosts to pool. Time:2/16/14 11:43 AM
>2014-02-16 11:43:14,124 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.RefreshStoragePoolVDSCommand] (org.ovirt.thread.pool-6-thread-13) [cdfa358] START, RefreshStoragePoolVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, masterStorageDomainId=f0c702c0-aec8-49c2-a48c-0bf9e1fd447d, masterVersion=1), log id: 7fba2602
>2014-02-16 11:43:14,137 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.RefreshStoragePoolVDSCommand] (org.ovirt.thread.pool-6-thread-13) [cdfa358] FINISH, RefreshStoragePoolVDSCommand, log id: 7fba2602
>2014-02-16 11:43:14,138 INFO  [org.ovirt.engine.core.bll.storage.RefreshPoolSingleAsyncOperation] (org.ovirt.thread.pool-6-thread-13) [cdfa358] Refreshed vds venus-vdsa in pool DC33
>2014-02-16 11:43:14,234 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (org.ovirt.thread.pool-6-thread-7) [cdfa358] ActivateStorage Domain. After change storage pool status in vds. Time:2/16/14 11:43 AM
>2014-02-16 11:43:14,239 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetIsoListVDSCommand] (org.ovirt.thread.pool-6-thread-15) [cdfa358] START, GetIsoListVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false), log id: 390aec8a
>2014-02-16 11:43:14,244 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HsmGetIsoListVDSCommand] (org.ovirt.thread.pool-6-thread-15) [cdfa358] START, HsmGetIsoListVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, storagePoolId=af2c9167-5d67-4494-ae09-def33cf383c2), log id: 4489e141
>2014-02-16 11:43:14,267 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HsmGetIsoListVDSCommand] (org.ovirt.thread.pool-6-thread-15) [cdfa358] FINISH, HsmGetIsoListVDSCommand, return: {RHEV-toolsSetup_2.2_47513.iso={size=-1}, fakeISO01.iso={size=-1}, RHEV-toolsSetup_2.2_52976.iso={size=-1}}, log id: 4489e141
>2014-02-16 11:43:14,267 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetIsoListVDSCommand] (org.ovirt.thread.pool-6-thread-15) [cdfa358] FINISH, GetIsoListVDSCommand, return: {RHEV-toolsSetup_2.2_47513.iso={size=-1}, fakeISO01.iso={size=-1}, RHEV-toolsSetup_2.2_52976.iso={size=-1}}, log id: 390aec8a
>2014-02-16 11:43:15,270 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetFloppyListVDSCommand] (org.ovirt.thread.pool-6-thread-15) [cdfa358] START, GetFloppyListVDSCommand( storagePoolId = af2c9167-5d67-4494-ae09-def33cf383c2, ignoreFailoverLimit = false), log id: 7cd7d5ea
>2014-02-16 11:43:15,282 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetFloppyListVDSCommand] (org.ovirt.thread.pool-6-thread-15) [cdfa358] FINISH, GetFloppyListVDSCommand, return: {}, log id: 7cd7d5ea
>2014-02-16 11:43:15,609 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-7) [cdfa358] Correlation ID: cdfa358, Job ID: da0cddac-630d-4ce6-bd9b-0617496caac4, Call Stack: null, Custom Event ID: -1, Message: Storage Domain iso_ovirt (Data Center DC33) was activated by admin
>2014-02-16 11:43:31,788 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http--0.0.0.0-8080-1) [77137ba0] START, IsVmDuringInitiatingVDSCommand( vmId = 3479c888-98f3-4d71-ad3b-b91f6082d889), log id: 390cba99
>2014-02-16 11:43:31,790 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http--0.0.0.0-8080-1) [77137ba0] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 390cba99
>2014-02-16 11:43:31,874 INFO  [org.ovirt.engine.core.bll.RunVmOnceCommand] (http--0.0.0.0-8080-1) [77137ba0] Running command: RunVmOnceCommand internal: false. Entities affected :  ID: 3479c888-98f3-4d71-ad3b-b91f6082d889 Type: VM
>2014-02-16 11:43:31,919 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (http--0.0.0.0-8080-1) [77137ba0] Running VM with attached cd RHEV-toolsSetup_2.2_47513.iso
>2014-02-16 11:43:31,923 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (http--0.0.0.0-8080-1) [77137ba0] START, IsoPrefixVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, storagePoolId=af2c9167-5d67-4494-ae09-def33cf383c2), log id: a747a93
>2014-02-16 11:43:31,937 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (http--0.0.0.0-8080-1) [77137ba0] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/multipass.eng.lab.tlv.redhat.com:_export_images_rnd_maor_iso__ovirt/0bff6863-2bdc-45da-8bcb-8349537f53ff/images/11111111-1111-1111-1111-111111111111, log id: a747a93
>2014-02-16 11:43:31,955 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (http--0.0.0.0-8080-1) [77137ba0] START, CreateVmVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=3479c888-98f3-4d71-ad3b-b91f6082d889, vm=VM [VMToTest]), log id: 58076af9
>2014-02-16 11:43:31,986 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (http--0.0.0.0-8080-1) [77137ba0] START, CreateVDSCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vmId=3479c888-98f3-4d71-ad3b-b91f6082d889, vm=VM [VMToTest]), log id: 4fb5f4ae
>2014-02-16 11:43:32,073 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (http--0.0.0.0-8080-1) [77137ba0] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand memSize=1024,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=rhel6.4.0,keyboardLayout=en-us,memGuaranteedSize=1024,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,maxVCpus=160,timeOffset=0,transparentHugePages=true,vmId=3479c888-98f3-4d71-ad3b-b91f6082d889,devices=[{address={bus=0x00, domain=0x0000, slot=0x02, type=pci, function=0x0}, specParams={vram=32768, heads=1}, device=qxl, type=video, deviceId=7e8a62b0-e75e-4382-a190-ff66e019f18b}, {shared=false, bootOrder=2, iface=ide, index=2, specParams={}, path=/rhev/data-center/mnt/multipass.eng.lab.tlv.redhat.com:_export_images_rnd_maor_iso__ovirt/0bff6863-2bdc-45da-8bcb-8349537f53ff/images/11111111-1111-1111-1111-111111111111/RHEV-toolsSetup_2.2_47513.iso, device=cdrom, type=disk, readonly=true, deviceId=d6159a11-938f-45b8-a142-8ca24f2b0a55}, {shared=false, index=0, volumeID=f0070ae0-6b73-4c54-b168-62618c422739, propagateErrors=off, format=raw, type=disk, iface=virtio, bootOrder=1, address={bus=0x00, domain=0x0000, slot=0x06, type=pci, function=0x0}, domainID=f0c702c0-aec8-49c2-a48c-0bf9e1fd447d, imageID=f97ffe51-171f-4df2-922e-ca48e6d8c09b, specParams={}, optional=false, device=disk, poolID=af2c9167-5d67-4494-ae09-def33cf383c2, readonly=false, deviceId=f97ffe51-171f-4df2-922e-ca48e6d8c09b}, {shared=false, volumeID=f3956d5d-56cb-424c-8dee-8b2d5961ca22, iface=virtio, imageID=04835ce6-50f8-4a1a-920e-5598db180951, domainID=f0c702c0-aec8-49c2-a48c-0bf9e1fd447d, address={bus=0x00, domain=0x0000, slot=0x07, type=pci, function=0x0}, specParams={}, optional=false, propagateErrors=off, device=disk, poolID=af2c9167-5d67-4494-ae09-def33cf383c2, format=raw, type=disk, readonly=false, deviceId=04835ce6-50f8-4a1a-920e-5598db180951}, {bootOrder=3, nicModel=pv, address={bus=0x00, domain=0x0000, slot=0x03, type=pci, function=0x0}, specParams={}, macAddr=00:1a:4a:16:01:52, device=bridge, linkActive=true, type=interface, filter=vdsm-no-mac-spoofing, network=, deviceId=6cfa739c-e3e7-4689-a811-13c9769dc681}, {specParams={model=virtio}, device=memballoon, type=balloon, deviceId=f1e3f739-3e8f-4c19-b4c0-49d2bf8ecc11}, {index=0, model=virtio-scsi, address={bus=0x00, domain=0x0000, slot=0x04, type=pci, function=0x0}, specParams={}, device=scsi, type=controller, deviceId=11ccb4b5-1f90-4959-80b1-5c571b63dbf3}],acpiEnable=true,vmName=VMToTest,cpuType=Conroe,custom={device_f9d1e61f-8833-4129-be53-125b201e4fa5device_63c0f886-ec7b-4b0b-81c0-797a7ab324eadevice_da6c1afe-ab00-4273-bac0-d1cbaeb95913=VmDevice {vmId=3479c888-98f3-4d71-ad3b-b91f6082d889, deviceId=da6c1afe-ab00-4273-bac0-d1cbaeb95913, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=1, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel0, customProperties={}, snapshotId=null}, device_f9d1e61f-8833-4129-be53-125b201e4fa5=VmDevice {vmId=3479c888-98f3-4d71-ad3b-b91f6082d889, deviceId=f9d1e61f-8833-4129-be53-125b201e4fa5, device=ide, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x1}, managed=false, plugged=true, readOnly=false, deviceAlias=ide0, customProperties={}, snapshotId=null}, device_f9d1e61f-8833-4129-be53-125b201e4fa5device_63c0f886-ec7b-4b0b-81c0-797a7ab324eadevice_da6c1afe-ab00-4273-bac0-d1cbaeb95913device_f761f881-6a9f-4897-9d43-9342368a2518device_49e8f5f8-fbb5-4b0a-a88c-465c9c4b873b=VmDevice {vmId=3479c888-98f3-4d71-ad3b-b91f6082d889, deviceId=49e8f5f8-fbb5-4b0a-a88c-465c9c4b873b, device=spicevmc, type=CHANNEL, bootOrder=0, specParams={}, address={port=3, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel2, customProperties={}, snapshotId=null}, device_f9d1e61f-8833-4129-be53-125b201e4fa5device_63c0f886-ec7b-4b0b-81c0-797a7ab324eadevice_da6c1afe-ab00-4273-bac0-d1cbaeb95913device_f761f881-6a9f-4897-9d43-9342368a2518=VmDevice {vmId=3479c888-98f3-4d71-ad3b-b91f6082d889, deviceId=f761f881-6a9f-4897-9d43-9342368a2518, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=2, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel1, customProperties={}, snapshotId=null}, device_f9d1e61f-8833-4129-be53-125b201e4fa5device_63c0f886-ec7b-4b0b-81c0-797a7ab324ea=VmDevice {vmId=3479c888-98f3-4d71-ad3b-b91f6082d889, deviceId=63c0f886-ec7b-4b0b-81c0-797a7ab324ea, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x05, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}, snapshotId=null}}
>2014-02-16 11:43:32,082 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (http--0.0.0.0-8080-1) [77137ba0] FINISH, CreateVDSCommand, log id: 4fb5f4ae
>2014-02-16 11:43:32,183 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (http--0.0.0.0-8080-1) [77137ba0] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 58076af9
>2014-02-16 11:43:32,224 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [77137ba0] Correlation ID: 77137ba0, Job ID: 3f9afcf1-e11b-42d7-a273-5e70e2400f45, Call Stack: null, Custom Event ID: -1, Message: VM VMToTest was started by admin (Host: venus-vdsa).
>2014-02-16 11:43:34,595 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-84) VM VMToTest 3479c888-98f3-4d71-ad3b-b91f6082d889 moved from WaitForLaunch --> PoweringUp
>2014-02-16 11:43:34,599 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-84) START, FullListVdsCommand(HostName = venus-vdsa, HostId = 95070901-19ea-4f27-9852-bb106cb88cb3, vds=Host[venus-vdsa], vmIds=[3479c888-98f3-4d71-ad3b-b91f6082d889]), log id: 4fd9344a
>2014-02-16 11:43:34,612 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-84) FINISH, FullListVdsCommand, return: [Ljava.util.HashMap;@3cf80f3a, log id: 4fd9344a
>2014-02-16 11:44:32,732 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-44) VM VMToTest 3479c888-98f3-4d71-ad3b-b91f6082d889 moved from PoweringUp --> Up
>2014-02-16 11:44:32,774 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-44) Correlation ID: 77137ba0, Job ID: 3f9afcf1-e11b-42d7-a273-5e70e2400f45, Call Stack: null, Custom Event ID: -1, Message: VM VMToTest started on Host venus-vdsa

Actions: View

Attachments on bug 1065719: 863678 | 864076