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 917903 Details for
Bug 1119376
Warning about OVF disk when creating a domain
[?]
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), 195.79 KB, created by
Gilad Chaplik
on 2014-07-14 15:59:17 UTC
(
hide
)
Description:
engine-log
Filename:
MIME Type:
Creator:
Gilad Chaplik
Created:
2014-07-14 15:59:17 UTC
Size:
195.79 KB
patch
obsolete
>2014-07-14 18:34:56,882 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Loaded file "/home/gchaplik/ovirt-engine/share/ovirt-engine/services/ovirt-engine/ovirt-engine.conf". >2014-07-14 18:34:56,886 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) The file "/home/gchaplik/ovirt-engine/etc/ovirt-engine/engine.conf" doesn't exist or isn't readable. Will return an empty set of properties. >2014-07-14 18:34:56,888 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Loaded file "/home/gchaplik/ovirt-engine/etc/ovirt-engine/engine.conf.d/10-setup-database.conf". >2014-07-14 18:34:56,889 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Loaded file "/home/gchaplik/ovirt-engine/etc/ovirt-engine/engine.conf.d/10-setup-jboss.conf". >2014-07-14 18:34:56,890 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Loaded file "/home/gchaplik/ovirt-engine/etc/ovirt-engine/engine.conf.d/10-setup-pki.conf". >2014-07-14 18:34:56,891 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Loaded file "/home/gchaplik/ovirt-engine/etc/ovirt-engine/engine.conf.d/10-setup-protocols.conf". >2014-07-14 18:34:56,892 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_AJP_ENABLED" is "false". >2014-07-14 18:34:56,892 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_AJP_PORT" is "None". >2014-07-14 18:34:56,893 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-07-14 18:34:56,894 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_CACHE" is "/home/gchaplik/ovirt-engine/var/cache/ovirt-engine". >2014-07-14 18:34:56,895 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_CHECK_INTERVAL" is "1000". >2014-07-14 18:34:56,895 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_CONNECTION_TIMEOUT" is "300000". >2014-07-14 18:34:56,896 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_DATABASE" is "engine". >2014-07-14 18:34:56,896 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_DRIVER" is "org.postgresql.Driver". >2014-07-14 18:34:56,897 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_HOST" is "localhost". >2014-07-14 18:34:56,897 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_MAX_CONNECTIONS" is "100". >2014-07-14 18:34:56,898 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_MIN_CONNECTIONS" is "1". >2014-07-14 18:34:56,898 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_PASSWORD" is "***". >2014-07-14 18:34:56,899 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_PORT" is "5432". >2014-07-14 18:34:56,899 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_SECURED" is "False". >2014-07-14 18:34:56,899 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_SECURED_VALIDATION" is "False". >2014-07-14 18:34:56,900 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-07-14 18:34:56,901 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DB_USER" is "engine". >2014-07-14 18:34:56,901 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-07-14 18:34:56,902 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_DOC" is "/home/gchaplik/ovirt-engine/share/doc/ovirt-engine". >2014-07-14 18:34:56,902 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_ETC" is "/home/gchaplik/ovirt-engine/etc/ovirt-engine". >2014-07-14 18:34:56,903 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_EXTENSION_PATH" is "/home/gchaplik/ovirt-engine/share/ovirt-engine/extensions.d:/home/gchaplik/ovirt-engine/etc/ovirt-engine/extensions.d". >2014-07-14 18:34:56,903 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_FQDN" is "localhost.localdomain". >2014-07-14 18:34:56,904 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_GROUP" is "ovirt". >2014-07-14 18:34:56,904 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_HEAP_MAX" is "1g". >2014-07-14 18:34:56,905 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_HEAP_MIN" is "1g". >2014-07-14 18:34:56,905 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_HTTPS_ENABLED" is "true". >2014-07-14 18:34:56,906 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_HTTPS_PORT" is "8443". >2014-07-14 18:34:56,906 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-07-14 18:34:56,907 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_HTTP_ENABLED" is "true". >2014-07-14 18:34:56,907 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_HTTP_PORT" is "8080". >2014-07-14 18:34:56,908 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_JAVA_MODULEPATH" is "/home/gchaplik/ovirt-engine/share/ovirt-engine/modules". >2014-07-14 18:34:56,908 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/gchaplik/ovirt-engine/var/log/ovirt-engine/dump"". >2014-07-14 18:34:56,909 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_LOG" is "/home/gchaplik/ovirt-engine/var/log/ovirt-engine". >2014-07-14 18:34:56,910 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_LOG_TO_CONSOLE" is "true". >2014-07-14 18:34:56,910 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_MANUAL" is "/home/gchaplik/ovirt-engine/share/ovirt-engine/manual". >2014-07-14 18:34:56,911 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PERM_MAX" is "256m". >2014-07-14 18:34:56,911 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PERM_MIN" is "256m". >2014-07-14 18:34:56,912 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI" is "/home/gchaplik/ovirt-engine/etc/pki/ovirt-engine". >2014-07-14 18:34:56,912 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI_CA" is "/home/gchaplik/ovirt-engine/etc/pki/ovirt-engine/ca.pem". >2014-07-14 18:34:56,913 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI_ENGINE_CERT" is "/home/gchaplik/ovirt-engine/etc/pki/ovirt-engine/certs/engine.cer". >2014-07-14 18:34:56,914 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI_ENGINE_STORE" is "/home/gchaplik/ovirt-engine/etc/pki/ovirt-engine/keys/engine.p12". >2014-07-14 18:34:56,914 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI_ENGINE_STORE_ALIAS" is "1". >2014-07-14 18:34:56,915 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI_ENGINE_STORE_PASSWORD" is "***". >2014-07-14 18:34:56,915 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI_TRUST_STORE" is "/home/gchaplik/ovirt-engine/etc/pki/ovirt-engine/.truststore". >2014-07-14 18:34:56,916 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PKI_TRUST_STORE_PASSWORD" is "***". >2014-07-14 18:34:56,916 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/gchaplik/ovirt-engine/etc/ovirt-engine/krb5.conf"". >2014-07-14 18:34:56,917 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PROXY_ENABLED" is "false". >2014-07-14 18:34:56,917 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PROXY_HTTPS_PORT" is "443". >2014-07-14 18:34:56,918 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_PROXY_HTTP_PORT" is "80". >2014-07-14 18:34:56,919 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_REPORTS_UI" is "/home/gchaplik/ovirt-engine/var/lib/ovirt-engine/reports.xml". >2014-07-14 18:34:56,920 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_STOP_INTERVAL" is "1". >2014-07-14 18:34:56,920 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_STOP_TIME" is "10". >2014-07-14 18:34:56,921 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_TMP" is "/home/gchaplik/ovirt-engine/var/tmp/ovirt-engine". >2014-07-14 18:34:56,921 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_UP_MARK" is "/home/gchaplik/ovirt-engine/var/lib/ovirt-engine/engine.up". >2014-07-14 18:34:56,922 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_URI" is "/ovirt-engine". >2014-07-14 18:34:56,922 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_USER" is "ovirt". >2014-07-14 18:34:56,923 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_USR" is "/home/gchaplik/ovirt-engine/share/ovirt-engine". >2014-07-14 18:34:56,923 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_VAR" is "/home/gchaplik/ovirt-engine/var/lib/ovirt-engine". >2014-07-14 18:34:56,924 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "ENGINE_VERBOSE_GC" is "false". >2014-07-14 18:34:56,924 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "JBOSS_HOME" is "/usr/share/jboss-as". >2014-07-14 18:34:56,925 INFO [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-7) Value of property "JBOSS_RUNTIME" is "/home/gchaplik/ovirt-engine/var/lib/ovirt-engine/jboss_runtime". >2014-07-14 18:34:56,926 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-07-14 18:34:57,240 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-4) Start initializing Backend >2014-07-14 18:34:57,426 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-4) Could not parse option AutoRecoveryAllowedTypes value. >2014-07-14 18:34:57,484 INFO [org.ovirt.engine.core.utils.osinfo.OsInfoPreferencesLoader] (MSC service thread 1-4) Loaded file /home/gchaplik/ovirt-engine/etc/ovirt-engine/osinfo.conf.d/00-defaults.properties >2014-07-14 18:34:57,558 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-4) Running ovirt-engine 3.6.0_master >2014-07-14 18:34:57,558 INFO [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-4) Start initializing dictionaries >2014-07-14 18:34:57,560 INFO [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-4) Finished initializing dictionaries >2014-07-14 18:34:57,564 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-4) Start initializing AuditLogCleanupManager >2014-07-14 18:34:57,565 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-4) Setting audit cleanup manager to run at: 35 35 3 * * ? >2014-07-14 18:34:57,579 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-4) Finished initializing AuditLogCleanupManager >2014-07-14 18:34:57,579 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-4) Start initializing CommandEntityCleanupManager >2014-07-14 18:34:57,580 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-4) Setting command entity cleanup manager to run at: 35 35 3 * * ? >2014-07-14 18:34:57,582 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-4) Finished initializing CommandEntityCleanupManager >2014-07-14 18:34:57,585 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-4) Start initializing TagsDirector >2014-07-14 18:34:57,600 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-4) Tag root added to tree >2014-07-14 18:34:57,613 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-4) Finished initializing TagsDirector >2014-07-14 18:34:57,614 INFO [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-4) Start initializing IsoDomainListSyncronizer >2014-07-14 18:34:57,620 INFO [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-4) Finished initializing IsoDomainListSyncronizer >2014-07-14 18:34:57,686 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-4) Completed initializing handlers >2014-07-14 18:34:57,712 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-4) Start initializing ErrorTranslatorImpl >2014-07-14 18:34:57,727 WARN [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-4) Code MAC_ADDRESS_IS_IN_USE appears more than once in string table. >2014-07-14 18:34:57,728 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-4) Finished initializing ErrorTranslatorImpl >2014-07-14 18:34:57,728 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-4) Start initializing ErrorTranslatorImpl >2014-07-14 18:34:57,729 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-4) Finished initializing ErrorTranslatorImpl >2014-07-14 18:34:57,729 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-4) Mark incomplete jobs as UNKNOWN >2014-07-14 18:34:57,751 INFO [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-4) Start initializing JobRepositoryCleanupManager >2014-07-14 18:34:57,752 INFO [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-4) Finished initializing JobRepositoryCleanupManager >2014-07-14 18:34:57,752 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-4) Start initializing AutoRecoveryManager >2014-07-14 18:34:57,753 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-4) Finished initializing AutoRecoveryManager >2014-07-14 18:34:57,754 INFO [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-4) Start initializing ExecutionMessageDirector >2014-07-14 18:34:57,756 INFO [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-4) Finished initializing ExecutionMessageDirector >2014-07-14 18:34:57,796 INFO [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-4) Start of enabled extensions list >2014-07-14 18:34:57,797 INFO [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-4) Instance name: 'builtin-authn-internal', Extension name: 'Internal Authn (Built-in)', Version: 'N/A', Notes: '', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0', File: 'N/A', Initialized: 'true' >2014-07-14 18:34:57,797 INFO [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-4) Instance name: 'internal', Extension name: 'Internal Authz (Built-in)', Version: 'N/A', Notes: '', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0', File: 'N/A', Initialized: 'true' >2014-07-14 18:34:57,798 INFO [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-4) End of enabled extensions list >2014-07-14 18:34:57,840 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (MSC service thread 1-6) Start initializing DbUserCacheManager >2014-07-14 18:34:57,842 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (MSC service thread 1-6) Finished initializing DbUserCacheManager >2014-07-14 18:34:57,852 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (MSC service thread 1-6) Initialization of AsyncTaskManager completed successfully. >2014-07-14 18:34:57,857 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-6) Start initializing ResourceManager >2014-07-14 18:34:57,874 INFO [org.ovirt.engine.core.bll.SyncUsers] (DefaultQuartzScheduler_Worker-1) The user admin from authz extension internal got updated since last interval >2014-07-14 18:34:57,934 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-6) Finished initializing ResourceManager >2014-07-14 18:34:57,938 INFO [org.ovirt.engine.core.bll.OvfDataUpdater] (MSC service thread 1-6) Initialization of OvfDataUpdater completed successfully. >2014-07-14 18:34:57,939 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-6) Start scheduling to enable vds load balancer >2014-07-14 18:34:57,939 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-6) Finished scheduling to enable vds load balancer >2014-07-14 18:34:57,940 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-6) Start HA Reservation check >2014-07-14 18:34:57,940 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-6) Finished HA Reservation check >2014-07-14 18:34:57,943 INFO [org.ovirt.engine.core.bll.network.MacPoolManagerRanges] (org.ovirt.thread.pool-8-thread-1) Start initializing MacPoolManagerRanges >2014-07-14 18:34:57,947 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-6) Init VM custom properties utilities >2014-07-14 18:34:57,948 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-6) Init device custom properties utilities >2014-07-14 18:34:57,953 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-6) Initializing Scheduling manager >2014-07-14 18:34:57,958 INFO [org.ovirt.engine.core.bll.network.MacPoolManagerRanges] (org.ovirt.thread.pool-8-thread-1) Finished initializing. Available MACs in pool: 255 >2014-07-14 18:34:57,982 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-6) External scheduler disabled, discovery skipped >2014-07-14 18:34:57,983 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-6) Initialized Scheduling manager >2014-07-14 18:34:57,983 INFO [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-6) Initializing DWH Heart Beat >2014-07-14 18:34:57,984 INFO [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-6) DWH Heart Beat initialized >2014-07-14 18:35:29,869 ERROR [org.ovirt.engine.core.utils.servlet.ServletUtils] (http--0.0.0.0-8080-2) Can't read file "/home/gchaplik/ovirt-engine/etc/ovirt-engine/branding/00-ovirt.brand/patternfly/css/styles.min.css" for request "/ovirt-engine/webadmin/theme/00-ovirt.brand/patternfly/css/styles.min.css", will send a 404 error response. >2014-07-14 18:35:34,388 INFO [org.ovirt.engine.core.bll.LoginAdminUserCommand] (http--0.0.0.0-8080-1) Running command: LoginAdminUserCommand internal: false. >2014-07-14 18:35:34,392 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) Cannot convert the string Host ${VdsName} is not responding. It will stay in Connecting state for a grace period of ${Seconds} seconds and after that an attempt to fence the host will be issued. to AuditLogType, the key does not exist in the AuditLogType declared types >2014-07-14 18:35:34,395 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: UNASSIGNED not exist in string table >2014-07-14 18:35:34,395 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: VDS_AUTO_FENCE_STATUS not exist in string table >2014-07-14 18:35:34,396 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: VDS_AUTO_FENCE_STATUS_FAILED not exist in string table >2014-07-14 18:35:34,396 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: VDS_AUTO_FENCE_FAILED_CALL_FENCE_SPM not exist in string table >2014-07-14 18:35:34,396 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: VDS_HIGH_NETWORK_USE not exist in string table >2014-07-14 18:35:34,397 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: USER_FAILED_REMOVE_VM not exist in string table >2014-07-14 18:35:34,398 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: USER_RUN_UNLOCK_ENTITY_SCRIPT not exist in string table >2014-07-14 18:35:34,398 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: VDS_NETWORK_MTU_DIFFER_FROM_LOGICAL_NETWORK not exist in string table >2014-07-14 18:35:34,399 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: VDS_HOST_IN_CONNECTING_STATE not exist in string table >2014-07-14 18:35:34,400 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: STORAGE_ACTIVATE_ASYNC not exist in string table >2014-07-14 18:35:34,400 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: DWH_STOPPED not exist in string table >2014-07-14 18:35:34,401 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: DWH_STARTED not exist in string table >2014-07-14 18:35:34,401 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: DWH_ERROR not exist in string table >2014-07-14 18:35:34,402 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: USER_ADDED_DISK_PROFILE not exist in string table >2014-07-14 18:35:34,403 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: USER_FAILED_TO_ADD_DISK_PROFILE not exist in string table >2014-07-14 18:35:34,403 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: USER_REMOVED_DISK_PROFILE not exist in string table >2014-07-14 18:35:34,403 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: USER_FAILED_TO_REMOVE_DISK_PROFILE not exist in string table >2014-07-14 18:35:34,404 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: USER_UPDATED_DISK_PROFILE not exist in string table >2014-07-14 18:35:34,404 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: USER_FAILED_TO_UPDATE_DISK_PROFILE not exist in string table >2014-07-14 18:35:34,405 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: USER_REMOVE_AUDIT_LOG not exist in string table >2014-07-14 18:35:34,405 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: USER_REMOVE_AUDIT_LOG_FAILED not exist in string table >2014-07-14 18:35:34,406 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: USER_CLEAR_ALL_DISMISSED_AUDIT_LOG not exist in string table >2014-07-14 18:35:34,406 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: USER_CLEAR_ALL_DISMISSED_AUDIT_LOG_FAILED not exist in string table >2014-07-14 18:35:34,531 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-07-14 18:35:37,879 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (http--0.0.0.0-8080-1) Running command: LoginUserCommand internal: false. >2014-07-14 18:40:42,282 INFO [org.ovirt.engine.core.bll.storage.AddEmptyStoragePoolCommand] (http--0.0.0.0-8080-1) [28c61840] Running command: AddEmptyStoragePoolCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:40:42,327 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [28c61840] Correlation ID: 28c61840, Call Stack: null, Custom Event ID: -1, Message: Data Center dc34, Compatibility Version 3.4 and Quota Type DISABLED was added by admin >2014-07-14 18:47:31,265 INFO [org.ovirt.engine.core.bll.AddVdsGroupCommand] (http--0.0.0.0-8080-1) [66bcb67b] Running command: AddVdsGroupCommand internal: false. Entities affected : ID: 68055dba-2ea1-4ff1-864e-7c15d4b1334c Type: StoragePool >2014-07-14 18:47:31,397 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [66bcb67b] Correlation ID: 66bcb67b, Call Stack: null, Custom Event ID: -1, Message: Cluster cluster34 was added by admin >2014-07-14 18:47:45,278 INFO [org.ovirt.engine.core.bll.AddVdsCommand] (http--0.0.0.0-8080-1) [133f4a65] Running command: AddVdsCommand internal: false. Entities affected : ID: ffd463b3-b3aa-4f3d-b1f0-3fa0a1845ef9 Type: VdsGroups >2014-07-14 18:47:45,326 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (http--0.0.0.0-8080-1) [570a51f7] Running command: AddVdsSpmIdCommand internal: true. Entities affected : ID: 716e77f8-7fc6-458d-95f9-2d99e4ca6868 Type: VDS >2014-07-14 18:47:45,342 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (http--0.0.0.0-8080-1) [570a51f7] Lock freed to object EngineLock [exclusiveLocks= key: 68055dba-2ea1-4ff1-864e-7c15d4b1334c value: REGISTER_VDS >, sharedLocks= ] >2014-07-14 18:47:45,355 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (http--0.0.0.0-8080-1) [570a51f7] START, RemoveVdsVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868), log id: 701eed0c >2014-07-14 18:47:45,355 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (http--0.0.0.0-8080-1) [570a51f7] FINISH, RemoveVdsVDSCommand, log id: 701eed0c >2014-07-14 18:47:45,366 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (http--0.0.0.0-8080-1) [570a51f7] START, AddVdsVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868), log id: 650030c2 >2014-07-14 18:47:45,367 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (http--0.0.0.0-8080-1) [570a51f7] AddVds - entered , starting logic to add VDS 716e77f8-7fc6-458d-95f9-2d99e4ca6868 >2014-07-14 18:47:45,377 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (http--0.0.0.0-8080-1) [570a51f7] AddVds - VDS 716e77f8-7fc6-458d-95f9-2d99e4ca6868 was added, will try to add it to the resource manager >2014-07-14 18:47:45,379 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (http--0.0.0.0-8080-1) [570a51f7] Entered VdsManager constructor >2014-07-14 18:47:45,407 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (http--0.0.0.0-8080-1) [570a51f7] Initialize vdsBroker (10.35.4.198,54,321) >2014-07-14 18:47:45,454 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (http--0.0.0.0-8080-1) [570a51f7] VDS 716e77f8-7fc6-458d-95f9-2d99e4ca6868 was added to the Resource Manager >2014-07-14 18:47:45,455 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (http--0.0.0.0-8080-1) [570a51f7] FINISH, AddVdsVDSCommand, log id: 650030c2 >2014-07-14 18:47:45,480 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [570a51f7] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host 10.35.4.198. >2014-07-14 18:47:45,515 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-4) [6080588b] Lock Acquired to object EngineLock [exclusiveLocks= key: 716e77f8-7fc6-458d-95f9-2d99e4ca6868 value: VDS >, sharedLocks= ] >2014-07-14 18:47:45,520 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [570a51f7] Correlation ID: 133f4a65, Job ID: e5b938e1-0292-4f63-989c-7dc62f75352f, Call Stack: null, Custom Event ID: -1, Message: Host 10.35.4.198 was added by admin. >2014-07-14 18:47:45,531 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-4) [6080588b] Running command: InstallVdsInternalCommand internal: true. Entities affected : ID: 716e77f8-7fc6-458d-95f9-2d99e4ca6868 Type: VDS >2014-07-14 18:47:45,533 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-4) [6080588b] Before Installation host 716e77f8-7fc6-458d-95f9-2d99e4ca6868, 10.35.4.198 >2014-07-14 18:47:45,538 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-4) [6080588b] START, SetVdsStatusVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, status=Installing, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 7e792bbd >2014-07-14 18:47:45,555 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-4) [6080588b] FINISH, SetVdsStatusVDSCommand, log id: 7e792bbd >2014-07-14 18:47:45,583 INFO [org.ovirt.engine.core.bll.InstallerMessages] (org.ovirt.thread.pool-8-thread-4) [6080588b] Installation 10.35.4.198: Connected to host 10.35.4.198 with SSH key fingerprint: b8:f6:5c:2c:57:89:41:a3:6b:78:00:f0:85:d2:e5:5d >2014-07-14 18:47:45,601 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-4) [6080588b] Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Connected to host 10.35.4.198 with SSH key fingerprint: b8:f6:5c:2c:57:89:41:a3:6b:78:00:f0:85:d2:e5:5d. >2014-07-14 18:47:45,665 INFO [org.ovirt.engine.core.bll.VdsDeploy] (org.ovirt.thread.pool-8-thread-4) [6080588b] Installation of 10.35.4.198. 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/gchaplik/ovirt-engine/var/cache/ovirt-engine/ovirt-host-deploy.tar >2014-07-14 18:47:45,666 INFO [org.ovirt.engine.core.utils.archivers.tar.CachedTar] (org.ovirt.thread.pool-8-thread-4) Tarball '/home/gchaplik/ovirt-engine/var/cache/ovirt-engine/ovirt-host-deploy.tar' is missing, creating >2014-07-14 18:47:45,764 INFO [org.ovirt.engine.core.uutils.ssh.SSHDialog] (org.ovirt.thread.pool-8-thread-4) SSH execute root@10.35.4.198 'umask 0077; MYTMP="$(TMPDIR="${OVIRT_TMPDIR}" mktemp -t ovirt-XXXXXXXXXX)"; trap "chmod -R u+rwX \"${MYTMP}\" > /dev/null 2>&1; rm -fr \"${MYTMP}\" > /dev/null 2>&1" 0; rm -fr "${MYTMP}" && mkdir "${MYTMP}" && tar --warning=no-timestamp -C "${MYTMP}" -x && "${MYTMP}"/setup DIALOG/dialect=str:machine DIALOG/customization=bool:True' >2014-07-14 18:47:46,503 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Stage: Initializing >2014-07-14 18:47:46,512 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Stage: Initializing. >2014-07-14 18:47:46,528 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Stage: Environment setup >2014-07-14 18:47:46,533 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Stage: Environment setup. >2014-07-14 18:47:46,541 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Stage: Environment packages setup >2014-07-14 18:47:46,572 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Stage: Environment packages setup. >2014-07-14 18:47:48,465 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-87) Initializing Host: 10.35.4.198 >2014-07-14 18:47:56,074 INFO [org.ovirt.engine.core.bll.storage.AddEmptyStoragePoolCommand] (http--0.0.0.0-8080-1) [5b6fc6d] Running command: AddEmptyStoragePoolCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:47:56,103 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [5b6fc6d] Correlation ID: 5b6fc6d, Call Stack: null, Custom Event ID: -1, Message: Data Center dc33, Compatibility Version 3.3 and Quota Type DISABLED was added by admin >2014-07-14 18:47:57,844 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Stage: Programs detection >2014-07-14 18:47:57,866 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Stage: Programs detection. >2014-07-14 18:47:57,869 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Stage: Environment customization >2014-07-14 18:47:57,874 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Stage: Environment customization. >2014-07-14 18:47:58,517 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Logs at host located at: '/tmp/ovirt-host-deploy-20140714184746-qep3k0.log' >2014-07-14 18:47:58,530 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Logs at host located at: '/tmp/ovirt-host-deploy-20140714184746-qep3k0.log'. >2014-07-14 18:47:58,597 INFO [org.ovirt.engine.core.bll.VdsDeploy] (VdsDeploy) Host 10.35.4.198 reports unique id 44454C4C-3800-1056-8032-C8C04F34354A >2014-07-14 18:47:58,607 INFO [org.ovirt.engine.core.bll.VdsDeploy] (VdsDeploy) Assigning unique id 44454C4C-3800-1056-8032-C8C04F34354A to Host 10.35.4.198 >2014-07-14 18:47:58,696 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Stage: Setup validation >2014-07-14 18:47:58,716 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Stage: Setup validation. >2014-07-14 18:47:58,739 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Hardware supports virtualization >2014-07-14 18:47:58,750 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Hardware supports virtualization. >2014-07-14 18:47:59,224 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Stage: Transaction setup >2014-07-14 18:47:59,233 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Stage: Transaction setup. >2014-07-14 18:47:59,285 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Stage: Misc configuration >2014-07-14 18:47:59,295 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Stage: Misc configuration. >2014-07-14 18:47:59,295 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Stage: Package installation >2014-07-14 18:47:59,305 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Stage: Package installation. >2014-07-14 18:48:02,303 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Stage: Misc configuration >2014-07-14 18:48:02,330 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Stage: Misc configuration. >2014-07-14 18:48:02,667 INFO [org.ovirt.engine.core.bll.AddVdsGroupCommand] (http--0.0.0.0-8080-1) [1ffc7714] Running command: AddVdsGroupCommand internal: false. Entities affected : ID: 1acf6a79-0571-4d13-921c-d49ef1e0d9ec Type: StoragePool >2014-07-14 18:48:02,687 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [1ffc7714] Correlation ID: 1ffc7714, Call Stack: null, Custom Event ID: -1, Message: Cluster cluster33 was added by admin >2014-07-14 18:48:13,026 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Enrolling certificate >2014-07-14 18:48:13,045 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Enrolling certificate. >2014-07-14 18:48:14,371 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Stage: Transaction commit >2014-07-14 18:48:14,389 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Stage: Transaction commit. >2014-07-14 18:48:14,525 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Stage: Closing up >2014-07-14 18:48:14,546 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Stage: Closing up. >2014-07-14 18:48:14,913 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Restarting kdump >2014-07-14 18:48:14,933 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Restarting kdump. >2014-07-14 18:48:16,694 INFO [org.ovirt.engine.core.bll.AddVdsCommand] (http--0.0.0.0-8080-1) [22ae34b8] Running command: AddVdsCommand internal: false. Entities affected : ID: d988bc11-6be7-48f7-9de4-1b0b2353548a Type: VdsGroups >2014-07-14 18:48:16,710 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (http--0.0.0.0-8080-1) [eee92bd] Running command: AddVdsSpmIdCommand internal: true. Entities affected : ID: 137a97e4-82bd-4c84-8fff-5837e41c5287 Type: VDS >2014-07-14 18:48:16,724 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (http--0.0.0.0-8080-1) [eee92bd] Lock freed to object EngineLock [exclusiveLocks= key: 1acf6a79-0571-4d13-921c-d49ef1e0d9ec value: REGISTER_VDS >, sharedLocks= ] >2014-07-14 18:48:16,727 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (http--0.0.0.0-8080-1) [eee92bd] START, RemoveVdsVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287), log id: 65f31ec3 >2014-07-14 18:48:16,727 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (http--0.0.0.0-8080-1) [eee92bd] FINISH, RemoveVdsVDSCommand, log id: 65f31ec3 >2014-07-14 18:48:16,729 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (http--0.0.0.0-8080-1) [eee92bd] START, AddVdsVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287), log id: 3bf58d68 >2014-07-14 18:48:16,730 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (http--0.0.0.0-8080-1) [eee92bd] AddVds - entered , starting logic to add VDS 137a97e4-82bd-4c84-8fff-5837e41c5287 >2014-07-14 18:48:16,731 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (http--0.0.0.0-8080-1) [eee92bd] AddVds - VDS 137a97e4-82bd-4c84-8fff-5837e41c5287 was added, will try to add it to the resource manager >2014-07-14 18:48:16,732 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (http--0.0.0.0-8080-1) [eee92bd] Entered VdsManager constructor >2014-07-14 18:48:16,745 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (http--0.0.0.0-8080-1) [eee92bd] Initialize vdsBroker (10.35.4.87,54,321) >2014-07-14 18:48:16,746 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (http--0.0.0.0-8080-1) [eee92bd] VDS 137a97e4-82bd-4c84-8fff-5837e41c5287 was added to the Resource Manager >2014-07-14 18:48:16,746 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (http--0.0.0.0-8080-1) [eee92bd] FINISH, AddVdsVDSCommand, log id: 3bf58d68 >2014-07-14 18:48:16,750 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [eee92bd] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host 10.35.4.87. >2014-07-14 18:48:16,776 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [eee92bd] Correlation ID: 22ae34b8, Job ID: 6a37f271-5308-4e6c-9f79-0a60e08c2dc8, Call Stack: null, Custom Event ID: -1, Message: Host 10.35.4.87 was added by admin. >2014-07-14 18:48:16,778 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-5) [627f4e85] Lock Acquired to object EngineLock [exclusiveLocks= key: 137a97e4-82bd-4c84-8fff-5837e41c5287 value: VDS >, sharedLocks= ] >2014-07-14 18:48:16,798 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-5) [627f4e85] Running command: InstallVdsInternalCommand internal: true. Entities affected : ID: 137a97e4-82bd-4c84-8fff-5837e41c5287 Type: VDS >2014-07-14 18:48:16,799 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-5) [627f4e85] Before Installation host 137a97e4-82bd-4c84-8fff-5837e41c5287, 10.35.4.87 >2014-07-14 18:48:16,801 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-5) [627f4e85] START, SetVdsStatusVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, status=Installing, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 44ce3ee0 >2014-07-14 18:48:16,807 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-5) [627f4e85] FINISH, SetVdsStatusVDSCommand, log id: 44ce3ee0 >2014-07-14 18:48:16,831 INFO [org.ovirt.engine.core.bll.InstallerMessages] (org.ovirt.thread.pool-8-thread-5) [627f4e85] Installation 10.35.4.87: Connected to host 10.35.4.87 with SSH key fingerprint: f4:55:51:3e:f8:66:d4:12:f5:d1:5d:30:dd:07:d5:da >2014-07-14 18:48:16,847 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-5) [627f4e85] Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Connected to host 10.35.4.87 with SSH key fingerprint: f4:55:51:3e:f8:66:d4:12:f5:d1:5d:30:dd:07:d5:da. >2014-07-14 18:48:16,911 INFO [org.ovirt.engine.core.bll.VdsDeploy] (org.ovirt.thread.pool-8-thread-5) [627f4e85] Installation of 10.35.4.87. 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/gchaplik/ovirt-engine/var/cache/ovirt-engine/ovirt-host-deploy.tar >2014-07-14 18:48:16,914 INFO [org.ovirt.engine.core.uutils.ssh.SSHDialog] (org.ovirt.thread.pool-8-thread-5) SSH execute root@10.35.4.87 'umask 0077; MYTMP="$(TMPDIR="${OVIRT_TMPDIR}" mktemp -t ovirt-XXXXXXXXXX)"; trap "chmod -R u+rwX \"${MYTMP}\" > /dev/null 2>&1; rm -fr \"${MYTMP}\" > /dev/null 2>&1" 0; rm -fr "${MYTMP}" && mkdir "${MYTMP}" && tar --warning=no-timestamp -C "${MYTMP}" -x && "${MYTMP}"/setup DIALOG/dialect=str:machine DIALOG/customization=bool:True' >2014-07-14 18:48:18,409 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Stage: Initializing >2014-07-14 18:48:18,427 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Stage: Initializing. >2014-07-14 18:48:18,442 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Stage: Environment setup >2014-07-14 18:48:18,447 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Stage: Environment setup. >2014-07-14 18:48:18,529 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Stage: Environment packages setup >2014-07-14 18:48:18,539 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Stage: Environment packages setup. >2014-07-14 18:48:19,757 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-57) Initializing Host: 10.35.4.87 >2014-07-14 18:48:35,043 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Stage: Programs detection >2014-07-14 18:48:35,061 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Stage: Programs detection. >2014-07-14 18:48:35,069 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Stage: Environment customization >2014-07-14 18:48:35,074 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Stage: Environment customization. >2014-07-14 18:48:36,270 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Logs at host located at: '/tmp/ovirt-host-deploy-20140714184817-z7hyy5.log' >2014-07-14 18:48:36,284 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Logs at host located at: '/tmp/ovirt-host-deploy-20140714184817-z7hyy5.log'. >2014-07-14 18:48:36,310 INFO [org.ovirt.engine.core.bll.VdsDeploy] (VdsDeploy) Host 10.35.4.87 reports unique id 44454C4C-4400-104C-8059-B6C04F30354A >2014-07-14 18:48:36,322 INFO [org.ovirt.engine.core.bll.VdsDeploy] (VdsDeploy) Assigning unique id 44454C4C-4400-104C-8059-B6C04F30354A to Host 10.35.4.87 >2014-07-14 18:48:36,377 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Disabling Kdump integration >2014-07-14 18:48:36,389 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Disabling Kdump integration. >2014-07-14 18:48:36,409 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Stage: Setup validation >2014-07-14 18:48:36,420 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Stage: Setup validation. >2014-07-14 18:48:36,453 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Hardware supports virtualization >2014-07-14 18:48:36,457 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Hardware supports virtualization. >2014-07-14 18:48:37,100 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Stage: Transaction setup >2014-07-14 18:48:37,113 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Stage: Transaction setup. >2014-07-14 18:48:37,174 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Stage: Misc configuration >2014-07-14 18:48:37,181 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Stage: Misc configuration. >2014-07-14 18:48:37,181 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Stage: Package installation >2014-07-14 18:48:37,186 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Stage: Package installation. >2014-07-14 18:48:39,837 ERROR [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Failed to execute stage 'Closing up': Command '/bin/systemctl' failed to execute >2014-07-14 18:48:39,851 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Failed to install Host 10.35.4.198. Failed to execute stage 'Closing up': Command '/bin/systemctl' failed to execute. >2014-07-14 18:48:39,852 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Stage: Pre-termination >2014-07-14 18:48:39,857 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Stage: Pre-termination. >2014-07-14 18:48:39,867 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Retrieving installation logs to: '/home/gchaplik/ovirt-engine/var/log/ovirt-engine/host-deploy/ovirt-20140714184839-10.35.4.198-6080588b.log' >2014-07-14 18:48:39,877 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Retrieving installation logs to: '/home/gchaplik/ovirt-engine/var/log/ovirt-engine/host-deploy/ovirt-20140714184839-10.35.4.198-6080588b.log'. >2014-07-14 18:48:40,090 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.198: Stage: Termination >2014-07-14 18:48:40,103 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 6080588b, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.198. Stage: Termination. >2014-07-14 18:48:40,140 ERROR [org.ovirt.engine.core.uutils.ssh.SSHDialog] (org.ovirt.thread.pool-8-thread-4) SSH error running command root@10.35.4.198:'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.io.IOException: Command returned failure code 1 during SSH session 'root@10.35.4.198' > 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:1135) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1253) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1872) [bll.jar:] > at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:174) [utils.jar:] > at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:116) [utils.jar:] > at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1274) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:345) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runAction(Backend.java:430) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:411) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runInternalAction(Backend.java:616) [bll.jar:] > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_55] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_55] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_55] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_55] > at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:114) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:125) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:135) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:82) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:211) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:363) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:194) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:173) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.ovirt.engine.core.bll.interfaces.BackendInternal$$$view7.runInternalAction(Unknown Source) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInternalAction(CommandBase.java:2174) [bll.jar:] > at org.ovirt.engine.core.bll.AddVdsCommand$3.run(AddVdsCommand.java:190) [bll.jar:] > at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:90) [utils.jar:] > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_55] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_55] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_55] > >2014-07-14 18:48:40,152 ERROR [org.ovirt.engine.core.bll.VdsDeploy] (org.ovirt.thread.pool-8-thread-4) [6080588b] Error during host 10.35.4.198 install: java.io.IOException: Command returned failure code 1 during SSH session 'root@10.35.4.198' > 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:1135) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1253) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1872) [bll.jar:] > at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:174) [utils.jar:] > at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:116) [utils.jar:] > at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1274) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:345) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runAction(Backend.java:430) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:411) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runInternalAction(Backend.java:616) [bll.jar:] > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_55] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_55] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_55] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_55] > at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:114) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:125) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:135) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:82) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:211) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:363) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:194) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:173) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.ovirt.engine.core.bll.interfaces.BackendInternal$$$view7.runInternalAction(Unknown Source) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInternalAction(CommandBase.java:2174) [bll.jar:] > at org.ovirt.engine.core.bll.AddVdsCommand$3.run(AddVdsCommand.java:190) [bll.jar:] > at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:90) [utils.jar:] > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_55] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_55] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_55] > >2014-07-14 18:48:40,165 ERROR [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-4) [6080588b] Host installation failed for host 716e77f8-7fc6-458d-95f9-2d99e4ca6868, 10.35.4.198.: java.io.IOException: Command returned failure code 1 during SSH session 'root@10.35.4.198' > 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:1135) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1253) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1872) [bll.jar:] > at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:174) [utils.jar:] > at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:116) [utils.jar:] > at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1274) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:345) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runAction(Backend.java:430) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:411) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runInternalAction(Backend.java:616) [bll.jar:] > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_55] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_55] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_55] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_55] > at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:114) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:125) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:135) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:82) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:211) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:363) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:194) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:173) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final] > at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.ovirt.engine.core.bll.interfaces.BackendInternal$$$view7.runInternalAction(Unknown Source) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInternalAction(CommandBase.java:2174) [bll.jar:] > at org.ovirt.engine.core.bll.AddVdsCommand$3.run(AddVdsCommand.java:190) [bll.jar:] > at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:90) [utils.jar:] > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_55] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_55] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_55] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_55] > >2014-07-14 18:48:40,179 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-4) [6080588b] START, SetVdsStatusVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, status=InstallFailed, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 2a90657f >2014-07-14 18:48:40,187 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-4) [6080588b] FINISH, SetVdsStatusVDSCommand, log id: 2a90657f >2014-07-14 18:48:40,203 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-4) [6080588b] Correlation ID: 6080588b, Job ID: e5b938e1-0292-4f63-989c-7dc62f75352f, Call Stack: null, Custom Event ID: -1, Message: Host 10.35.4.198 installation failed. Command returned failure code 1 during SSH session 'root@10.35.4.198'. >2014-07-14 18:48:40,215 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-4) [6080588b] Lock freed to object EngineLock [exclusiveLocks= key: 716e77f8-7fc6-458d-95f9-2d99e4ca6868 value: VDS >, sharedLocks= ] >2014-07-14 18:48:43,599 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Stage: Misc configuration >2014-07-14 18:48:43,606 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Stage: Misc configuration. >2014-07-14 18:48:54,411 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Enrolling certificate >2014-07-14 18:48:54,423 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Enrolling certificate. >2014-07-14 18:48:55,747 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Stage: Transaction commit >2014-07-14 18:48:55,760 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Stage: Transaction commit. >2014-07-14 18:48:55,811 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Stage: Closing up >2014-07-14 18:48:55,818 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Stage: Closing up. >2014-07-14 18:49:03,572 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Stopping libvirtd >2014-07-14 18:49:03,587 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Stopping libvirtd. >2014-07-14 18:49:03,851 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Starting vdsm >2014-07-14 18:49:03,865 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Starting vdsm. >2014-07-14 18:49:08,008 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Stage: Pre-termination >2014-07-14 18:49:08,022 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Stage: Pre-termination. >2014-07-14 18:49:08,033 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Retrieving installation logs to: '/home/gchaplik/ovirt-engine/var/log/ovirt-engine/host-deploy/ovirt-20140714184908-10.35.4.87-627f4e85.log' >2014-07-14 18:49:08,039 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Retrieving installation logs to: '/home/gchaplik/ovirt-engine/var/log/ovirt-engine/host-deploy/ovirt-20140714184908-10.35.4.87-627f4e85.log'. >2014-07-14 18:49:08,271 INFO [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.35.4.87: Stage: Termination >2014-07-14 18:49:08,287 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 627f4e85, Call Stack: null, Custom Event ID: -1, Message: Installing Host 10.35.4.87. Stage: Termination. >2014-07-14 18:49:08,380 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.PollVDSCommand] (org.ovirt.thread.pool-8-thread-5) [627f4e85] java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException >2014-07-14 18:49:08,393 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.PollVDSCommand] (org.ovirt.thread.pool-8-thread-5) [627f4e85] Command PollVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287) execution failed. Exception: RuntimeException: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException >2014-07-14 18:49:08,988 INFO [org.ovirt.engine.core.bll.network.NetworkConfigurator] (org.ovirt.thread.pool-8-thread-5) [627f4e85] Engine managed to communicate with VDSM agent on host 10.35.4.87 >2014-07-14 18:49:09,322 INFO [org.ovirt.engine.core.bll.network.NetworkConfigurator] (org.ovirt.thread.pool-8-thread-5) [627f4e85] The management network ovirtmgmt is already configured on host 10.35.4.87 >2014-07-14 18:49:09,324 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-5) [627f4e85] START, SetVdsStatusVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, status=Initializing, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: e7777ee >2014-07-14 18:49:09,329 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-5) [627f4e85] FINISH, SetVdsStatusVDSCommand, log id: e7777ee >2014-07-14 18:49:09,329 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-5) [627f4e85] After Installation host 10.35.4.87, VDS >2014-07-14 18:49:09,341 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-5) [627f4e85] Correlation ID: 627f4e85, Job ID: 6a37f271-5308-4e6c-9f79-0a60e08c2dc8, Call Stack: null, Custom Event ID: -1, Message: Host 10.35.4.87 installed >2014-07-14 18:49:09,352 INFO [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-8-thread-5) [627f4e85] Lock freed to object EngineLock [exclusiveLocks= key: 137a97e4-82bd-4c84-8fff-5837e41c5287 value: VDS >, sharedLocks= ] >2014-07-14 18:49:10,937 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-89) START, GetHardwareInfoVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, vds=Host[10.35.4.87,137a97e4-82bd-4c84-8fff-5837e41c5287]), log id: 5461605f >2014-07-14 18:49:10,983 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-89) FINISH, GetHardwareInfoVDSCommand, log id: 5461605f >2014-07-14 18:49:10,996 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-89) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Host 10.35.4.87 does not enforce SELinux. >2014-07-14 18:49:10,997 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-89) Host 10.35.4.87 does not report SELinux enforcement information. >2014-07-14 18:49:11,022 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (DefaultQuartzScheduler_Worker-89) [497fe0aa] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: 137a97e4-82bd-4c84-8fff-5837e41c5287 Type: VDS >2014-07-14 18:49:11,077 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-89) [497fe0aa] START, GetHardwareInfoVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, vds=Host[10.35.4.87,137a97e4-82bd-4c84-8fff-5837e41c5287]), log id: 6c97db0 >2014-07-14 18:49:11,085 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-89) [497fe0aa] FINISH, GetHardwareInfoVDSCommand, log id: 6c97db0 >2014-07-14 18:49:11,088 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-89) [497fe0aa] Host 10.35.4.87 does not report SELinux enforcement information. >2014-07-14 18:49:11,129 INFO [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (DefaultQuartzScheduler_Worker-89) [45676d10] Running command: InitVdsOnUpCommand internal: true. Entities affected : ID: 1acf6a79-0571-4d13-921c-d49ef1e0d9ec Type: StoragePool >2014-07-14 18:49:11,145 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-89) [45676d10] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Kdump integration is enabled for host '10.35.4.87', but kdump is not configured properly on host. >2014-07-14 18:49:11,161 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (DefaultQuartzScheduler_Worker-89) [45676d10] START, SetMOMPolicyParametersVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287), log id: 4a652fcf >2014-07-14 18:49:11,238 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (DefaultQuartzScheduler_Worker-89) [45676d10] FINISH, SetMOMPolicyParametersVDSCommand, log id: 4a652fcf >2014-07-14 18:49:11,245 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-89) [45676d10] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Status of host 10.35.4.87 was set to Up. >2014-07-14 18:49:11,249 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-89) [45676d10] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host 10.35.4.87. >2014-07-14 18:49:11,314 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (DefaultQuartzScheduler_Worker-89) [47c5e582] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: 137a97e4-82bd-4c84-8fff-5837e41c5287 Type: VDS >2014-07-14 18:49:11,321 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (DefaultQuartzScheduler_Worker-89) [667a0370] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: 137a97e4-82bd-4c84-8fff-5837e41c5287 Type: VDS >2014-07-14 18:49:11,429 WARN [org.ovirt.engine.core.bll.MaintenanceNumberOfVdssCommand] (http--0.0.0.0-8080-1) [75e39ec5] CanDoAction of action MaintenanceNumberOfVdss failed. Reasons:VAR__TYPE__HOST,VAR__ACTION__MAINTENANCE,VDS_CANNOT_MAINTENANCE_VDS_IS_NOT_OPERATIONAL >2014-07-14 18:49:49,650 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (http--0.0.0.0-8080-1) [46d86f16] Lock Acquired to object EngineLock [exclusiveLocks= key: 716e77f8-7fc6-458d-95f9-2d99e4ca6868 value: VDS >, sharedLocks= ] >2014-07-14 18:49:49,694 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-8-thread-44) [46d86f16] Running command: ActivateVdsCommand internal: false. Entities affected : ID: 716e77f8-7fc6-458d-95f9-2d99e4ca6868 Type: VDS >2014-07-14 18:49:49,695 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-8-thread-44) [46d86f16] Before acquiring lock in order to prevent monitoring for host 10.35.4.198 from data-center dc34 >2014-07-14 18:49:49,696 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-8-thread-44) [46d86f16] Lock acquired, from now a monitoring of host will be skipped for host 10.35.4.198 from data-center dc34 >2014-07-14 18:49:49,705 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-44) [46d86f16] START, SetVdsStatusVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, status=Unassigned, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 1d570b21 >2014-07-14 18:49:49,712 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-8-thread-44) [46d86f16] FINISH, SetVdsStatusVDSCommand, log id: 1d570b21 >2014-07-14 18:49:49,748 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-8-thread-44) [46d86f16] Activate finished. Lock released. Monitoring can run now for host 10.35.4.198 from data-center dc34 >2014-07-14 18:49:49,768 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-44) [46d86f16] Correlation ID: 46d86f16, Job ID: 1df7fb2c-12d5-451a-b7cd-bb51dc73001d, Call Stack: null, Custom Event ID: -1, Message: Host 10.35.4.198 was activated by admin. >2014-07-14 18:49:49,778 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-8-thread-44) [46d86f16] Lock freed to object EngineLock [exclusiveLocks= key: 716e77f8-7fc6-458d-95f9-2d99e4ca6868 value: VDS >, sharedLocks= ] >2014-07-14 18:49:52,061 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-90) START, GetHardwareInfoVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, vds=Host[10.35.4.198,716e77f8-7fc6-458d-95f9-2d99e4ca6868]), log id: 69c3da01 >2014-07-14 18:49:52,114 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-90) FINISH, GetHardwareInfoVDSCommand, log id: 69c3da01 >2014-07-14 18:49:52,171 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (DefaultQuartzScheduler_Worker-90) [612c2306] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: 716e77f8-7fc6-458d-95f9-2d99e4ca6868 Type: VDS >2014-07-14 18:49:52,239 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-90) [612c2306] START, GetHardwareInfoVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, vds=Host[10.35.4.198,716e77f8-7fc6-458d-95f9-2d99e4ca6868]), log id: 3f2c7cc4 >2014-07-14 18:49:52,248 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-90) [612c2306] FINISH, GetHardwareInfoVDSCommand, log id: 3f2c7cc4 >2014-07-14 18:49:52,362 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-90) [612c2306] START, FullListVdsCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, vds=Host[10.35.4.198,716e77f8-7fc6-458d-95f9-2d99e4ca6868], vmIds=[dc518130-d5f0-455f-b5eb-76f471f41cf4]), log id: 5dc5ae2c >2014-07-14 18:49:52,371 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-90) [612c2306] FINISH, FullListVdsCommand, return: [{displaySecurePort=5900, kvmEnable=true, nicModel=rtl8139,pv, guestIPs=, keyboardLayout=en-us, displayIp=10.35.4.198, nice=0, displayNetwork=ovirtmgmt, username=Unknown, copyPasteEnable=true, timeOffset=0, transparentHugePages=true, vmId=dc518130-d5f0-455f-b5eb-76f471f41cf4, acpiEnable=true, custom={device_d6b4126a-1dfe-4384-b174-63c6fe09024fdevice_1ba33b3c-4c8e-4482-9343-9d5d9a4822eedevice_290a0056-baa2-466d-9d81-267048633d81device_a8a791dc-f963-4cb7-a45f-cfa52af4fd93device_08af55c5-8cdc-4c91-88c3-0cb4bf8f1925=VmDevice {vmId=dc518130-d5f0-455f-b5eb-76f471f41cf4, deviceId=08af55c5-8cdc-4c91-88c3-0cb4bf8f1925, 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_d6b4126a-1dfe-4384-b174-63c6fe09024fdevice_1ba33b3c-4c8e-4482-9343-9d5d9a4822eedevice_290a0056-baa2-466d-9d81-267048633d81device_a8a791dc-f963-4cb7-a45f-cfa52af4fd93=VmDevice {vmId=dc518130-d5f0-455f-b5eb-76f471f41cf4, deviceId=a8a791dc-f963-4cb7-a45f-cfa52af4fd93, 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_d6b4126a-1dfe-4384-b174-63c6fe09024fdevice_1ba33b3c-4c8e-4482-9343-9d5d9a4822ee=VmDevice {vmId=dc518130-d5f0-455f-b5eb-76f471f41cf4, deviceId=1ba33b3c-4c8e-4482-9343-9d5d9a4822ee, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x04, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}, snapshotId=null}, device_d6b4126a-1dfe-4384-b174-63c6fe09024f=VmDevice {vmId=dc518130-d5f0-455f-b5eb-76f471f41cf4, deviceId=d6b4126a-1dfe-4384-b174-63c6fe09024f, 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_d6b4126a-1dfe-4384-b174-63c6fe09024fdevice_1ba33b3c-4c8e-4482-9343-9d5d9a4822eedevice_290a0056-baa2-466d-9d81-267048633d81=VmDevice {vmId=dc518130-d5f0-455f-b5eb-76f471f41cf4, deviceId=290a0056-baa2-466d-9d81-267048633d81, 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}}, spiceSslCipherSuite=DEFAULT, memSize=1024, smp=1, displayPort=-1, status=Paused, emulatedMachine=pc-1.0, vmType=kvm, memGuaranteedSize=1024, display=qxl, pid=6988, smartcardEnable=false, bootMenuEnable=false, numaTune={mode=preferred}, spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard, guestFQDN=, smpCoresPerSocket=1, maxVCpus=160, clientIp=, devices=[Ljava.lang.Object;@38acb3a8, vmName=vm11, fileTransferEnable=true, cpuType=Conroe}], log id: 5dc5ae2c >2014-07-14 18:49:52,380 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-90) [612c2306] Importing VM vm11 as external-vm11, as it is running on the on Host, but does not exist in the engine. >2014-07-14 18:49:52,406 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-90) [612c2306] VDS::UpdateVmRunTimeInfo Error: found VM on a VDS that is not in the database! >2014-07-14 18:49:52,410 INFO [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (DefaultQuartzScheduler_Worker-90) [54d1545b] Running command: InitVdsOnUpCommand internal: true. Entities affected : ID: 68055dba-2ea1-4ff1-864e-7c15d4b1334c Type: StoragePool >2014-07-14 18:49:52,424 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-90) [54d1545b] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Kdump integration is enabled for host '10.35.4.198', but kdump is not configured properly on host. >2014-07-14 18:49:52,436 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (DefaultQuartzScheduler_Worker-90) [54d1545b] START, SetMOMPolicyParametersVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868), log id: 3e48df03 >2014-07-14 18:49:52,529 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (DefaultQuartzScheduler_Worker-90) [54d1545b] FINISH, SetMOMPolicyParametersVDSCommand, log id: 3e48df03 >2014-07-14 18:49:52,541 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-90) [54d1545b] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Status of host 10.35.4.198 was set to Up. >2014-07-14 18:49:52,545 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-90) [54d1545b] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host 10.35.4.198. >2014-07-14 18:49:52,630 INFO [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (DefaultQuartzScheduler_Worker-90) [7b25e9af] Lock Acquired to object EngineLock [exclusiveLocks= key: external-vm11 value: VM_NAME >, sharedLocks= ] >2014-07-14 18:49:52,656 INFO [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (DefaultQuartzScheduler_Worker-90) [7b25e9af] Running command: AddVmFromScratchCommand internal: true. Entities affected : ID: ffd463b3-b3aa-4f3d-b1f0-3fa0a1845ef9 Type: VdsGroups >2014-07-14 18:49:52,870 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-90) [7b25e9af] Correlation ID: 7b25e9af, Call Stack: null, Custom Event ID: -1, Message: VM external-vm11 was created by Non interactive user. >2014-07-14 18:49:52,870 INFO [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (DefaultQuartzScheduler_Worker-90) [7b25e9af] Lock freed to object EngineLock [exclusiveLocks= key: external-vm11 value: VM_NAME >, sharedLocks= ] >2014-07-14 18:49:52,879 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (DefaultQuartzScheduler_Worker-90) [aecc596] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: 716e77f8-7fc6-458d-95f9-2d99e4ca6868 Type: VDS >2014-07-14 18:49:52,890 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (DefaultQuartzScheduler_Worker-90) [549be58a] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: 716e77f8-7fc6-458d-95f9-2d99e4ca6868 Type: VDS >2014-07-14 18:49:55,947 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-99) START, FullListVdsCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, vds=Host[10.35.4.198,716e77f8-7fc6-458d-95f9-2d99e4ca6868], vmIds=[dc518130-d5f0-455f-b5eb-76f471f41cf4]), log id: 121ea55f >2014-07-14 18:49:55,955 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-99) FINISH, FullListVdsCommand, return: [{displaySecurePort=5900, kvmEnable=true, nicModel=rtl8139,pv, guestIPs=, keyboardLayout=en-us, displayIp=10.35.4.198, nice=0, displayNetwork=ovirtmgmt, username=Unknown, copyPasteEnable=true, timeOffset=0, transparentHugePages=true, vmId=dc518130-d5f0-455f-b5eb-76f471f41cf4, acpiEnable=true, custom={device_d6b4126a-1dfe-4384-b174-63c6fe09024fdevice_1ba33b3c-4c8e-4482-9343-9d5d9a4822eedevice_290a0056-baa2-466d-9d81-267048633d81device_a8a791dc-f963-4cb7-a45f-cfa52af4fd93device_08af55c5-8cdc-4c91-88c3-0cb4bf8f1925=VmDevice {vmId=dc518130-d5f0-455f-b5eb-76f471f41cf4, deviceId=08af55c5-8cdc-4c91-88c3-0cb4bf8f1925, 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_d6b4126a-1dfe-4384-b174-63c6fe09024fdevice_1ba33b3c-4c8e-4482-9343-9d5d9a4822eedevice_290a0056-baa2-466d-9d81-267048633d81device_a8a791dc-f963-4cb7-a45f-cfa52af4fd93=VmDevice {vmId=dc518130-d5f0-455f-b5eb-76f471f41cf4, deviceId=a8a791dc-f963-4cb7-a45f-cfa52af4fd93, 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_d6b4126a-1dfe-4384-b174-63c6fe09024fdevice_1ba33b3c-4c8e-4482-9343-9d5d9a4822ee=VmDevice {vmId=dc518130-d5f0-455f-b5eb-76f471f41cf4, deviceId=1ba33b3c-4c8e-4482-9343-9d5d9a4822ee, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x04, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}, snapshotId=null}, device_d6b4126a-1dfe-4384-b174-63c6fe09024f=VmDevice {vmId=dc518130-d5f0-455f-b5eb-76f471f41cf4, deviceId=d6b4126a-1dfe-4384-b174-63c6fe09024f, 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_d6b4126a-1dfe-4384-b174-63c6fe09024fdevice_1ba33b3c-4c8e-4482-9343-9d5d9a4822eedevice_290a0056-baa2-466d-9d81-267048633d81=VmDevice {vmId=dc518130-d5f0-455f-b5eb-76f471f41cf4, deviceId=290a0056-baa2-466d-9d81-267048633d81, 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}}, spiceSslCipherSuite=DEFAULT, memSize=1024, smp=1, displayPort=-1, status=Paused, emulatedMachine=pc-1.0, vmType=kvm, memGuaranteedSize=1024, display=qxl, pid=6988, smartcardEnable=false, bootMenuEnable=false, numaTune={mode=preferred}, spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard, guestFQDN=, smpCoresPerSocket=1, maxVCpus=160, clientIp=, devices=[Ljava.lang.Object;@57affdd6, vmName=vm11, fileTransferEnable=true, cpuType=Conroe}], log id: 121ea55f >2014-07-14 18:49:55,961 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-99) Received a memballoon Device without an address when processing VM dc518130-d5f0-455f-b5eb-76f471f41cf4 devices, skipping device: {alias=balloon0, specParams={model=none}, device=memballoon, type=balloon} >2014-07-14 18:49:55,962 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-99) Received a spice Device without an address when processing VM dc518130-d5f0-455f-b5eb-76f471f41cf4 devices, skipping device: {specParams={spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard, keyMap=en-us, displayNetwork=ovirtmgmt, copyPasteEnable=true, displayIp=10.35.4.198}, device=spice, tlsPort=5900, type=graphics} >2014-07-14 18:50:06,391 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-30) [2998b20] Running command: ShutdownVmCommand internal: false. Entities affected : ID: dc518130-d5f0-455f-b5eb-76f471f41cf4 Type: VM >2014-07-14 18:50:06,394 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-30) [2998b20] Entered (VM external-vm11). >2014-07-14 18:50:06,394 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (org.ovirt.thread.pool-8-thread-30) [2998b20] Cannot shutdown VM external-vm11, status is not up. Stopping instead. >2014-07-14 18:50:06,443 INFO [org.ovirt.engine.core.bll.StopVmCommand] (org.ovirt.thread.pool-8-thread-30) [7dfab1c8] Running command: StopVmCommand internal: true. Entities affected : ID: dc518130-d5f0-455f-b5eb-76f471f41cf4 Type: VM >2014-07-14 18:50:06,448 INFO [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-30) [7dfab1c8] START, DestroyVmVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, vmId=dc518130-d5f0-455f-b5eb-76f471f41cf4, force=false, secondsToWait=0, gracefully=false, reason=), log id: 526cf57d >2014-07-14 18:50:06,470 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-30) [7dfab1c8] START, DestroyVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, vmId=dc518130-d5f0-455f-b5eb-76f471f41cf4, force=false, secondsToWait=0, gracefully=false, reason=), log id: 5c0cc880 >2014-07-14 18:50:06,750 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (org.ovirt.thread.pool-8-thread-30) [7dfab1c8] FINISH, DestroyVDSCommand, log id: 5c0cc880 >2014-07-14 18:50:06,766 INFO [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (org.ovirt.thread.pool-8-thread-30) [7dfab1c8] FINISH, DestroyVmVDSCommand, return: Down, log id: 526cf57d >2014-07-14 18:50:06,790 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-30) [7dfab1c8] Correlation ID: 7dfab1c8, Job ID: 1e8cf80c-bad9-4999-8a7d-1b3b2bd785b7, Call Stack: null, Custom Event ID: -1, Message: VM external-vm11 was powered off ungracefully by admin (Host: 10.35.4.198) (Reason: Not Specified). >2014-07-14 18:50:06,800 INFO [org.ovirt.engine.core.bll.ProcessDownVmCommand] (org.ovirt.thread.pool-8-thread-32) [1fd74564] Running command: ProcessDownVmCommand internal: true. >2014-07-14 18:50:15,613 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (http--0.0.0.0-8080-1) [4d3796b8] Lock Acquired to object EngineLock [exclusiveLocks= key: dc518130-d5f0-455f-b5eb-76f471f41cf4 value: VM >, sharedLocks= ] >2014-07-14 18:50:15,624 WARN [org.ovirt.engine.core.bll.RemoveVmCommand] (http--0.0.0.0-8080-1) [4d3796b8] CanDoAction of action RemoveVm failed. Reasons:VAR__ACTION__REMOVE,VAR__TYPE__VM,ACTION_TYPE_FAILED_IMAGE_REPOSITORY_NOT_FOUND >2014-07-14 18:50:15,624 INFO [org.ovirt.engine.core.bll.RemoveVmCommand] (http--0.0.0.0-8080-1) [4d3796b8] Lock freed to object EngineLock [exclusiveLocks= key: dc518130-d5f0-455f-b5eb-76f471f41cf4 value: VM >, sharedLocks= ] >2014-07-14 18:50:38,212 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: Refresh image list succeeded for domain(s): ovirt-image-repository (All file type) >2014-07-14 18:51:27,775 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (http--0.0.0.0-8080-1) [1c1b2d98] Lock Acquired to object EngineLock [exclusiveLocks= key: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data1 value: STORAGE_CONNECTION >, sharedLocks= ] >2014-07-14 18:51:27,797 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (http--0.0.0.0-8080-1) [1c1b2d98] Running command: AddStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:51:27,800 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [1c1b2d98] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data1, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 71478f84 >2014-07-14 18:51:27,972 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [1c1b2d98] FINISH, ConnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 71478f84 >2014-07-14 18:51:27,990 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (http--0.0.0.0-8080-1) [1c1b2d98] Lock freed to object EngineLock [exclusiveLocks= key: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data1 value: STORAGE_CONNECTION >, sharedLocks= ] >2014-07-14 18:51:28,054 INFO [org.ovirt.engine.core.bll.storage.AddNFSStorageDomainCommand] (http--0.0.0.0-8080-1) [56da6659] Running command: AddNFSStorageDomainCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:51:28,074 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [56da6659] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: b1f5bf97-9c7b-48a2-a2dc-b7c3fda363d8, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data1, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 5b666190 >2014-07-14 18:51:28,089 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [56da6659] FINISH, ConnectStorageServerVDSCommand, return: {b1f5bf97-9c7b-48a2-a2dc-b7c3fda363d8=0}, log id: 5b666190 >2014-07-14 18:51:28,092 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (http--0.0.0.0-8080-1) [56da6659] START, CreateStorageDomainVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storageDomain=org.ovirt.engine.core.common.businessentities.StorageDomainStatic@cd97add7, args=10.35.16.25:/volumes/smamit/frontend/gchaplik/data1), log id: 31d5ebb6 >2014-07-14 18:51:28,818 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (http--0.0.0.0-8080-1) [56da6659] FINISH, CreateStorageDomainVDSCommand, log id: 31d5ebb6 >2014-07-14 18:51:28,819 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (http--0.0.0.0-8080-1) [56da6659] START, GetStorageDomainStatsVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storageDomainId=ce5c53df-3c4f-4f98-892b-5c6e941f52f8), log id: 32fdd99a >2014-07-14 18:51:28,829 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (http--0.0.0.0-8080-1) [56da6659] FINISH, GetStorageDomainStatsVDSCommand, return: org.ovirt.engine.core.common.businessentities.StorageDomain@d7a3eef9, log id: 32fdd99a >2014-07-14 18:51:28,861 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [56da6659] Correlation ID: 56da6659, Job ID: 739b6537-25a1-4551-b7cd-19a3922cbdf5, Call Stack: null, Custom Event ID: -1, Message: Storage Domain data1 was added by admin >2014-07-14 18:51:28,924 INFO [org.ovirt.engine.core.bll.storage.DisconnectStorageServerConnectionCommand] (http--0.0.0.0-8080-1) [d352593] Running command: DisconnectStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:51:28,928 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [d352593] START, DisconnectStorageServerVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: b1f5bf97-9c7b-48a2-a2dc-b7c3fda363d8, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data1, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 1de6a4e >2014-07-14 18:51:29,014 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [d352593] FINISH, DisconnectStorageServerVDSCommand, return: {b1f5bf97-9c7b-48a2-a2dc-b7c3fda363d8=0}, log id: 1de6a4e >2014-07-14 18:51:29,113 INFO [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] (http--0.0.0.0-8080-1) [4e8ac727] Running command: AttachStorageDomainToPoolCommand internal: false. Entities affected : ID: ce5c53df-3c4f-4f98-892b-5c6e941f52f8 Type: Storage >2014-07-14 18:51:29,129 INFO [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (http--0.0.0.0-8080-1) [17b2ff69] Lock Acquired to object EngineLock [exclusiveLocks= key: 1acf6a79-0571-4d13-921c-d49ef1e0d9ec value: POOL >, sharedLocks= ] >2014-07-14 18:51:29,144 INFO [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (http--0.0.0.0-8080-1) [17b2ff69] Running command: AddStoragePoolWithStoragesCommand internal: true. Entities affected : ID: 1acf6a79-0571-4d13-921c-d49ef1e0d9ec Type: StoragePool >2014-07-14 18:51:29,207 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (http--0.0.0.0-8080-1) [1f67c66b] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:51:29,210 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [1f67c66b] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: b1f5bf97-9c7b-48a2-a2dc-b7c3fda363d8, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data1, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 247cf6ec >2014-07-14 18:51:29,302 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [1f67c66b] FINISH, ConnectStorageServerVDSCommand, return: {b1f5bf97-9c7b-48a2-a2dc-b7c3fda363d8=0}, log id: 247cf6ec >2014-07-14 18:51:29,304 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStoragePoolVDSCommand] (http--0.0.0.0-8080-1) [1f67c66b] START, CreateStoragePoolVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storagePoolId=1acf6a79-0571-4d13-921c-d49ef1e0d9ec, storagePoolName=dc33, masterDomainId=ce5c53df-3c4f-4f98-892b-5c6e941f52f8, domainsIdList=[ce5c53df-3c4f-4f98-892b-5c6e941f52f8], masterVersion=1), log id: 74c2b067 >2014-07-14 18:51:51,075 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (http--0.0.0.0-8080-5) [422c0979] Lock Acquired to object EngineLock [exclusiveLocks= key: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data2 value: STORAGE_CONNECTION >, sharedLocks= ] >2014-07-14 18:51:51,089 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (http--0.0.0.0-8080-5) [422c0979] Running command: AddStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:51:51,090 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-5) [422c0979] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data2, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 152e010b >2014-07-14 18:51:51,278 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-5) [422c0979] FINISH, ConnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 152e010b >2014-07-14 18:51:51,307 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (http--0.0.0.0-8080-5) [422c0979] Lock freed to object EngineLock [exclusiveLocks= key: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data2 value: STORAGE_CONNECTION >, sharedLocks= ] >2014-07-14 18:51:51,372 INFO [org.ovirt.engine.core.bll.storage.AddNFSStorageDomainCommand] (http--0.0.0.0-8080-5) [7972f4d5] Running command: AddNFSStorageDomainCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:51:51,384 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-5) [7972f4d5] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 37bde018-f2d8-4fc6-a835-4481cfee019d, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data2, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 79059d64 >2014-07-14 18:51:51,400 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-5) [7972f4d5] FINISH, ConnectStorageServerVDSCommand, return: {37bde018-f2d8-4fc6-a835-4481cfee019d=0}, log id: 79059d64 >2014-07-14 18:51:51,402 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (http--0.0.0.0-8080-5) [7972f4d5] START, CreateStorageDomainVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storageDomain=org.ovirt.engine.core.common.businessentities.StorageDomainStatic@4396332, args=10.35.16.25:/volumes/smamit/frontend/gchaplik/data2), log id: 3e5432aa >2014-07-14 18:51:51,986 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (http--0.0.0.0-8080-5) [7972f4d5] FINISH, CreateStorageDomainVDSCommand, log id: 3e5432aa >2014-07-14 18:51:51,986 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (http--0.0.0.0-8080-5) [7972f4d5] START, GetStorageDomainStatsVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storageDomainId=79818395-80e1-40af-adb6-328223a20ad9), log id: 66bcf1ff >2014-07-14 18:51:51,996 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (http--0.0.0.0-8080-5) [7972f4d5] FINISH, GetStorageDomainStatsVDSCommand, return: org.ovirt.engine.core.common.businessentities.StorageDomain@87fed3d9, log id: 66bcf1ff >2014-07-14 18:51:52,021 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-5) [7972f4d5] Correlation ID: 7972f4d5, Job ID: 2f04dd30-4c94-4410-bfc0-31a82e733d1c, Call Stack: null, Custom Event ID: -1, Message: Storage Domain data2 was added by admin >2014-07-14 18:51:52,077 INFO [org.ovirt.engine.core.bll.storage.DisconnectStorageServerConnectionCommand] (http--0.0.0.0-8080-6) [59a2146e] Running command: DisconnectStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:51:52,078 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (http--0.0.0.0-8080-6) [59a2146e] START, DisconnectStorageServerVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 37bde018-f2d8-4fc6-a835-4481cfee019d, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data2, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 53b56a2c >2014-07-14 18:51:52,173 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (http--0.0.0.0-8080-6) [59a2146e] FINISH, DisconnectStorageServerVDSCommand, return: {37bde018-f2d8-4fc6-a835-4481cfee019d=0}, log id: 53b56a2c >2014-07-14 18:51:52,249 INFO [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] (http--0.0.0.0-8080-6) [3c188d70] Running command: AttachStorageDomainToPoolCommand internal: false. Entities affected : ID: 79818395-80e1-40af-adb6-328223a20ad9 Type: Storage >2014-07-14 18:51:52,262 INFO [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (http--0.0.0.0-8080-6) [28c377f9] Lock Acquired to object EngineLock [exclusiveLocks= key: 68055dba-2ea1-4ff1-864e-7c15d4b1334c value: POOL >, sharedLocks= ] >2014-07-14 18:51:52,281 INFO [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (http--0.0.0.0-8080-6) [28c377f9] Running command: AddStoragePoolWithStoragesCommand internal: true. Entities affected : ID: 68055dba-2ea1-4ff1-864e-7c15d4b1334c Type: StoragePool >2014-07-14 18:51:52,312 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (http--0.0.0.0-8080-6) [3b504545] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:51:52,315 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-6) [3b504545] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 37bde018-f2d8-4fc6-a835-4481cfee019d, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data2, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 6d5d138 >2014-07-14 18:51:52,386 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-6) [3b504545] FINISH, ConnectStorageServerVDSCommand, return: {37bde018-f2d8-4fc6-a835-4481cfee019d=0}, log id: 6d5d138 >2014-07-14 18:51:52,388 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStoragePoolVDSCommand] (http--0.0.0.0-8080-6) [3b504545] START, CreateStoragePoolVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId=68055dba-2ea1-4ff1-864e-7c15d4b1334c, storagePoolName=dc34, masterDomainId=79818395-80e1-40af-adb6-328223a20ad9, domainsIdList=[79818395-80e1-40af-adb6-328223a20ad9], masterVersion=1), log id: 40ed4fec >2014-07-14 18:51:54,477 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStoragePoolVDSCommand] (http--0.0.0.0-8080-1) [1f67c66b] FINISH, CreateStoragePoolVDSCommand, log id: 74c2b067 >2014-07-14 18:51:54,489 INFO [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (http--0.0.0.0-8080-1) [1f67c66b] Lock freed to object EngineLock [exclusiveLocks= key: 1acf6a79-0571-4d13-921c-d49ef1e0d9ec value: POOL >, sharedLocks= ] >2014-07-14 18:51:54,491 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [74ea666] Lock Acquired to object EngineLock [exclusiveLocks= key: ce5c53df-3c4f-4f98-892b-5c6e941f52f8 value: STORAGE >, sharedLocks= ] >2014-07-14 18:51:54,494 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [74ea666] Running command: ActivateStorageDomainCommand internal: true. Entities affected : ID: ce5c53df-3c4f-4f98-892b-5c6e941f52f8 Type: Storage >2014-07-14 18:51:54,507 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [74ea666] Lock freed to object EngineLock [exclusiveLocks= key: ce5c53df-3c4f-4f98-892b-5c6e941f52f8 value: STORAGE >, sharedLocks= ] >2014-07-14 18:51:54,507 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [74ea666] ActivateStorage Domain. Before Connect all hosts to pool. Time:7/14/14 6:51 PM >2014-07-14 18:51:54,510 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (org.ovirt.thread.pool-8-thread-4) [40ea9980] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:51:54,512 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-4) [40ea9980] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: b1f5bf97-9c7b-48a2-a2dc-b7c3fda363d8, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data1, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 2535b788 >2014-07-14 18:51:54,527 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-4) [40ea9980] FINISH, ConnectStorageServerVDSCommand, return: {b1f5bf97-9c7b-48a2-a2dc-b7c3fda363d8=0}, log id: 2535b788 >2014-07-14 18:51:54,529 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (http--0.0.0.0-8080-1) [74ea666] START, ActivateStorageDomainVDSCommand( storagePoolId = 1acf6a79-0571-4d13-921c-d49ef1e0d9ec, ignoreFailoverLimit = false, storageDomainId = ce5c53df-3c4f-4f98-892b-5c6e941f52f8), log id: 233308a8 >2014-07-14 18:51:54,539 INFO [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (http--0.0.0.0-8080-1) [3e62ef0a] Running command: SetStoragePoolStatusCommand internal: true. Entities affected : ID: 1acf6a79-0571-4d13-921c-d49ef1e0d9ec Type: StoragePool >2014-07-14 18:51:54,559 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [3e62ef0a] Correlation ID: 3e62ef0a, Call Stack: null, Custom Event ID: -1, Message: Data Center is being initialized, please wait for initialization to complete. >2014-07-14 18:51:54,572 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (http--0.0.0.0-8080-1) [3e62ef0a] hostFromVds::selectedVds - 10.35.4.87, spmStatus Unknown_Pool, storage pool dc33 >2014-07-14 18:51:54,579 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (http--0.0.0.0-8080-1) [3e62ef0a] START, ConnectStoragePoolVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, vdsId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storagePoolId = 1acf6a79-0571-4d13-921c-d49ef1e0d9ec, masterVersion = 1), log id: 7527b373 >2014-07-14 18:51:54,834 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (http--0.0.0.0-8080-1) [3e62ef0a] FINISH, ConnectStoragePoolVDSCommand, log id: 7527b373 >2014-07-14 18:51:54,842 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (http--0.0.0.0-8080-1) [3e62ef0a] hostFromVds::Connected host to pool - selectedVds - 10.35.4.87, spmStatus Free, storage pool dc33 >2014-07-14 18:51:54,851 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (http--0.0.0.0-8080-1) [3e62ef0a] starting spm on vds 10.35.4.87, storage pool dc33, prevId -1, LVER -1 >2014-07-14 18:51:54,855 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (http--0.0.0.0-8080-1) [3e62ef0a] START, SpmStartVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storagePoolId = 1acf6a79-0571-4d13-921c-d49ef1e0d9ec, prevId=-1, prevLVER=-1, storagePoolFormatType=V3, recoveryMode=Manual, SCSIFencing=false), log id: 20798754 >2014-07-14 18:51:54,869 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (http--0.0.0.0-8080-1) [3e62ef0a] spmStart polling started: taskId = 2310061a-64e6-4f75-a138-ecab5b5538a2 >2014-07-14 18:52:15,865 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStoragePoolVDSCommand] (http--0.0.0.0-8080-6) [3b504545] FINISH, CreateStoragePoolVDSCommand, log id: 40ed4fec >2014-07-14 18:52:15,876 INFO [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (http--0.0.0.0-8080-6) [3b504545] Lock freed to object EngineLock [exclusiveLocks= key: 68055dba-2ea1-4ff1-864e-7c15d4b1334c value: POOL >, sharedLocks= ] >2014-07-14 18:52:15,877 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-6) [27a7104a] Lock Acquired to object EngineLock [exclusiveLocks= key: 79818395-80e1-40af-adb6-328223a20ad9 value: STORAGE >, sharedLocks= ] >2014-07-14 18:52:15,880 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-6) [27a7104a] Running command: ActivateStorageDomainCommand internal: true. Entities affected : ID: 79818395-80e1-40af-adb6-328223a20ad9 Type: Storage >2014-07-14 18:52:15,885 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-6) [27a7104a] Lock freed to object EngineLock [exclusiveLocks= key: 79818395-80e1-40af-adb6-328223a20ad9 value: STORAGE >, sharedLocks= ] >2014-07-14 18:52:15,885 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-6) [27a7104a] ActivateStorage Domain. Before Connect all hosts to pool. Time:7/14/14 6:52 PM >2014-07-14 18:52:15,888 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (org.ovirt.thread.pool-8-thread-14) [17c05dbe] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:52:15,890 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-14) [17c05dbe] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 37bde018-f2d8-4fc6-a835-4481cfee019d, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data2, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 8b7d7fa >2014-07-14 18:52:15,906 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-14) [17c05dbe] FINISH, ConnectStorageServerVDSCommand, return: {37bde018-f2d8-4fc6-a835-4481cfee019d=0}, log id: 8b7d7fa >2014-07-14 18:52:15,907 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (http--0.0.0.0-8080-6) [27a7104a] START, ActivateStorageDomainVDSCommand( storagePoolId = 68055dba-2ea1-4ff1-864e-7c15d4b1334c, ignoreFailoverLimit = false, storageDomainId = 79818395-80e1-40af-adb6-328223a20ad9), log id: 18f9f4d4 >2014-07-14 18:52:15,914 INFO [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (http--0.0.0.0-8080-6) [65935655] Running command: SetStoragePoolStatusCommand internal: true. Entities affected : ID: 68055dba-2ea1-4ff1-864e-7c15d4b1334c Type: StoragePool >2014-07-14 18:52:15,943 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-6) [65935655] Correlation ID: 65935655, Call Stack: null, Custom Event ID: -1, Message: Data Center is being initialized, please wait for initialization to complete. >2014-07-14 18:52:15,950 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (http--0.0.0.0-8080-6) [65935655] hostFromVds::selectedVds - 10.35.4.198, spmStatus Unknown_Pool, storage pool dc34 >2014-07-14 18:52:15,953 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (http--0.0.0.0-8080-6) [65935655] START, ConnectStoragePoolVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, vdsId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 68055dba-2ea1-4ff1-864e-7c15d4b1334c, masterVersion = 1), log id: 50c76576 >2014-07-14 18:52:16,245 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (http--0.0.0.0-8080-6) [65935655] FINISH, ConnectStoragePoolVDSCommand, log id: 50c76576 >2014-07-14 18:52:16,252 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (http--0.0.0.0-8080-6) [65935655] hostFromVds::Connected host to pool - selectedVds - 10.35.4.198, spmStatus Free, storage pool dc34 >2014-07-14 18:52:16,263 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (http--0.0.0.0-8080-6) [65935655] starting spm on vds 10.35.4.198, storage pool dc34, prevId -1, LVER -1 >2014-07-14 18:52:16,264 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (http--0.0.0.0-8080-6) [65935655] START, SpmStartVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 68055dba-2ea1-4ff1-864e-7c15d4b1334c, prevId=-1, prevLVER=-1, storagePoolFormatType=V3, recoveryMode=Manual, SCSIFencing=false), log id: c667259 >2014-07-14 18:52:16,276 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (http--0.0.0.0-8080-6) [65935655] spmStart polling started: taskId = b753537e-04a1-4d45-9f06-2b98865fa552 >2014-07-14 18:52:17,004 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (http--0.0.0.0-8080-1) [3e62ef0a] spmStart polling ended: taskId = 2310061a-64e6-4f75-a138-ecab5b5538a2 task status = finished >2014-07-14 18:52:17,010 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (http--0.0.0.0-8080-1) [3e62ef0a] spmStart polling ended, spm status: SPM >2014-07-14 18:52:17,012 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (http--0.0.0.0-8080-1) [3e62ef0a] START, HSMClearTaskVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, taskId=2310061a-64e6-4f75-a138-ecab5b5538a2), log id: 7049d1c5 >2014-07-14 18:52:17,018 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (http--0.0.0.0-8080-1) [3e62ef0a] FINISH, HSMClearTaskVDSCommand, log id: 7049d1c5 >2014-07-14 18:52:17,018 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (http--0.0.0.0-8080-1) [3e62ef0a] FINISH, SpmStartVDSCommand, return: org.ovirt.engine.core.common.businessentities.SpmStatusResult@6cc59a3e, log id: 20798754 >2014-07-14 18:52:17,032 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (http--0.0.0.0-8080-1) [3e62ef0a] Initialize Irs proxy from vds: 10.35.4.87 >2014-07-14 18:52:17,036 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [3e62ef0a] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Storage Pool Manager runs on Host 10.35.4.87 (Address: 10.35.4.87). >2014-07-14 18:52:17,060 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (http--0.0.0.0-8080-1) [3e62ef0a] FINISH, ActivateStorageDomainVDSCommand, log id: 233308a8 >2014-07-14 18:52:17,060 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [3e62ef0a] ActivateStorage Domain. After Connect all hosts to pool. Time:7/14/14 6:52 PM >2014-07-14 18:52:17,077 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-8-thread-28) START, ConnectStoragePoolVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, vdsId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storagePoolId = 1acf6a79-0571-4d13-921c-d49ef1e0d9ec, masterVersion = 1), log id: 5f0d60c5 >2014-07-14 18:52:17,205 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-8-thread-28) FINISH, ConnectStoragePoolVDSCommand, log id: 5f0d60c5 >2014-07-14 18:52:17,206 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [3e62ef0a] ActivateStorage Domain. After change storage pool status in vds. Time:7/14/14 6:52 PM >2014-07-14 18:52:17,220 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [3e62ef0a] Correlation ID: 74ea666, Call Stack: null, Custom Event ID: -1, Message: Storage Domain data1 (Data Center dc33) was activated by admin >2014-07-14 18:52:17,233 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [3e62ef0a] Correlation ID: 17b2ff69, Job ID: a4b1a816-3043-4370-9965-dde9d4f7b500, Call Stack: null, Custom Event ID: -1, Message: Storage Domains were attached to Data Center dc33 by admin >2014-07-14 18:52:17,254 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [3e62ef0a] Correlation ID: 4e8ac727, Job ID: a4b1a816-3043-4370-9965-dde9d4f7b500, Call Stack: null, Custom Event ID: -1, Message: Storage Domain data1 was attached to Data Center dc33 by admin >2014-07-14 18:52:25,928 INFO [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (http--0.0.0.0-8080-1) [35a92fe1] Lock Acquired to object EngineLock [exclusiveLocks= key: vm1 value: VM_NAME >, sharedLocks= ] >2014-07-14 18:52:25,962 INFO [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (http--0.0.0.0-8080-1) [35a92fe1] Running command: AddVmFromScratchCommand internal: false. Entities affected : ID: d988bc11-6be7-48f7-9de4-1b0b2353548a Type: VdsGroups >2014-07-14 18:52:26,140 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [35a92fe1] Correlation ID: 35a92fe1, Job ID: 46396466-731e-4777-96c3-b4e894001922, Call Stack: null, Custom Event ID: -1, Message: VM vm1 was created by admin. >2014-07-14 18:52:26,145 INFO [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (http--0.0.0.0-8080-1) [35a92fe1] Lock freed to object EngineLock [exclusiveLocks= key: vm1 value: VM_NAME >, sharedLocks= ] >2014-07-14 18:52:26,353 INFO [org.ovirt.engine.core.bll.network.vm.ReorderVmNicsCommand] (http--0.0.0.0-8080-1) [6c36019] Running command: ReorderVmNicsCommand internal: false. Entities affected : ID: 67430212-9005-4932-935a-7bdcad48d7d6 Type: VM >2014-07-14 18:52:30,141 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (http--0.0.0.0-8080-1) [753f1e12] Lock Acquired to object EngineLock [exclusiveLocks= key: 67430212-9005-4932-935a-7bdcad48d7d6 value: VM_DISK_BOOT >, sharedLocks= key: 67430212-9005-4932-935a-7bdcad48d7d6 value: VM >] >2014-07-14 18:52:30,166 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (http--0.0.0.0-8080-1) [753f1e12] Running command: AddDiskCommand internal: false. Entities affected : ID: 67430212-9005-4932-935a-7bdcad48d7d6 Type: VM, ID: ce5c53df-3c4f-4f98-892b-5c6e941f52f8 Type: Storage >2014-07-14 18:52:30,241 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (http--0.0.0.0-8080-1) [c863852] Running command: AddImageFromScratchCommand internal: true. Entities affected : ID: ce5c53df-3c4f-4f98-892b-5c6e941f52f8 Type: Storage >2014-07-14 18:52:30,267 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (http--0.0.0.0-8080-1) [c863852] Lock freed to object EngineLock [exclusiveLocks= key: 67430212-9005-4932-935a-7bdcad48d7d6 value: VM_DISK_BOOT >, sharedLocks= key: 67430212-9005-4932-935a-7bdcad48d7d6 value: VM >] >2014-07-14 18:52:30,268 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (http--0.0.0.0-8080-1) [c863852] START, CreateImageVDSCommand( storagePoolId = 1acf6a79-0571-4d13-921c-d49ef1e0d9ec, ignoreFailoverLimit = false, storageDomainId = ce5c53df-3c4f-4f98-892b-5c6e941f52f8, imageGroupId = 23081bbf-2a02-4525-a324-58a4a5f290c1, imageSizeInBytes = 1073741824, volumeFormat = RAW, newImageId = 0604e288-3594-4067-98c9-113735e70fff, newImageDescription = ), log id: 6a9c5017 >2014-07-14 18:52:30,271 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (http--0.0.0.0-8080-1) [c863852] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID >2014-07-14 18:52:30,325 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (http--0.0.0.0-8080-1) [c863852] FINISH, CreateImageVDSCommand, return: 0604e288-3594-4067-98c9-113735e70fff, log id: 6a9c5017 >2014-07-14 18:52:30,410 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (http--0.0.0.0-8080-1) [c863852] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command b0d694ff-1b7b-4db7-917c-252d93e9653c >2014-07-14 18:52:30,411 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (http--0.0.0.0-8080-1) [c863852] CommandMultiAsyncTasks::AttachTask: Attaching task 73ccef33-4857-47c1-8e90-176b409f30b6 to command b0d694ff-1b7b-4db7-917c-252d93e9653c. >2014-07-14 18:52:30,434 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (http--0.0.0.0-8080-1) [c863852] Adding task 73ccef33-4857-47c1-8e90-176b409f30b6 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-14 18:52:30,478 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [c863852] Correlation ID: 753f1e12, Job ID: e8cdd81c-ae28-43df-9ee0-67c350690849, Call Stack: null, Custom Event ID: -1, Message: Add-Disk operation of vm1_Disk1 was initiated on VM vm1 by admin. >2014-07-14 18:52:30,479 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (http--0.0.0.0-8080-1) [c863852] BaseAsyncTask::startPollingTask: Starting to poll task 73ccef33-4857-47c1-8e90-176b409f30b6. >2014-07-14 18:52:37,852 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-29) Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now >2014-07-14 18:52:37,861 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-29) SPMAsyncTask::PollTask: Polling task 73ccef33-4857-47c1-8e90-176b409f30b6 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-14 18:52:37,883 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-29) BaseAsyncTask::OnTaskEndSuccess: Task 73ccef33-4857-47c1-8e90-176b409f30b6 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-14 18:52:37,884 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-29) CommandAsyncTask::EndActionIfNecessary: All tasks of command b0d694ff-1b7b-4db7-917c-252d93e9653c has ended -> executing endAction >2014-07-14 18:52:37,885 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-29) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: b0d694ff-1b7b-4db7-917c-252d93e9653c): calling endAction . >2014-07-14 18:52:37,886 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-43) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction AddDisk, executionIndex: 0 >2014-07-14 18:52:37,897 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (org.ovirt.thread.pool-8-thread-43) [753f1e12] Ending command successfully: org.ovirt.engine.core.bll.AddDiskCommand >2014-07-14 18:52:37,903 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (org.ovirt.thread.pool-8-thread-43) [c863852] Ending command successfully: org.ovirt.engine.core.bll.AddImageFromScratchCommand >2014-07-14 18:52:37,915 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-43) [c863852] START, GetImageInfoVDSCommand( storagePoolId = 1acf6a79-0571-4d13-921c-d49ef1e0d9ec, ignoreFailoverLimit = false, storageDomainId = ce5c53df-3c4f-4f98-892b-5c6e941f52f8, imageGroupId = 23081bbf-2a02-4525-a324-58a4a5f290c1, imageId = 0604e288-3594-4067-98c9-113735e70fff), log id: 1d5f4a40 >2014-07-14 18:52:37,978 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-43) [c863852] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@d9522d37, log id: 1d5f4a40 >2014-07-14 18:52:38,008 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-43) Correlation ID: 753f1e12, Call Stack: null, Custom Event ID: -1, Message: The disk vm1_Disk1 was successfully added to VM vm1. >2014-07-14 18:52:38,009 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-43) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk completed, handling the result. >2014-07-14 18:52:38,009 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-43) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk succeeded, clearing tasks. >2014-07-14 18:52:38,013 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-43) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 73ccef33-4857-47c1-8e90-176b409f30b6 >2014-07-14 18:52:38,014 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-43) START, SPMClearTaskVDSCommand( storagePoolId = 1acf6a79-0571-4d13-921c-d49ef1e0d9ec, ignoreFailoverLimit = false, taskId = 73ccef33-4857-47c1-8e90-176b409f30b6), log id: 3da642eb >2014-07-14 18:52:38,016 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-43) START, HSMClearTaskVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, taskId=73ccef33-4857-47c1-8e90-176b409f30b6), log id: 6e7371eb >2014-07-14 18:52:38,030 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-43) FINISH, HSMClearTaskVDSCommand, log id: 6e7371eb >2014-07-14 18:52:38,031 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-43) FINISH, SPMClearTaskVDSCommand, log id: 3da642eb >2014-07-14 18:52:38,050 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-43) BaseAsyncTask::RemoveTaskFromDB: Removed task 73ccef33-4857-47c1-8e90-176b409f30b6 from DataBase >2014-07-14 18:52:38,050 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-43) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity b0d694ff-1b7b-4db7-917c-252d93e9653c >2014-07-14 18:52:38,418 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (http--0.0.0.0-8080-6) [65935655] spmStart polling ended: taskId = b753537e-04a1-4d45-9f06-2b98865fa552 task status = finished >2014-07-14 18:52:38,424 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (http--0.0.0.0-8080-6) [65935655] spmStart polling ended, spm status: SPM >2014-07-14 18:52:38,425 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (http--0.0.0.0-8080-6) [65935655] START, HSMClearTaskVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, taskId=b753537e-04a1-4d45-9f06-2b98865fa552), log id: 14fa235d >2014-07-14 18:52:38,431 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (http--0.0.0.0-8080-6) [65935655] FINISH, HSMClearTaskVDSCommand, log id: 14fa235d >2014-07-14 18:52:38,431 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (http--0.0.0.0-8080-6) [65935655] FINISH, SpmStartVDSCommand, return: org.ovirt.engine.core.common.businessentities.SpmStatusResult@47122cfc, log id: c667259 >2014-07-14 18:52:38,442 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (http--0.0.0.0-8080-6) [65935655] Initialize Irs proxy from vds: 10.35.4.198 >2014-07-14 18:52:38,447 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-6) [65935655] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Storage Pool Manager runs on Host 10.35.4.198 (Address: 10.35.4.198). >2014-07-14 18:52:38,464 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (http--0.0.0.0-8080-6) [65935655] FINISH, ActivateStorageDomainVDSCommand, log id: 18f9f4d4 >2014-07-14 18:52:38,465 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-6) [65935655] ActivateStorage Domain. After Connect all hosts to pool. Time:7/14/14 6:52 PM >2014-07-14 18:52:38,481 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-8-thread-5) START, ConnectStoragePoolVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, vdsId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 68055dba-2ea1-4ff1-864e-7c15d4b1334c, masterVersion = 1), log id: 471a71a1 >2014-07-14 18:52:38,616 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-8-thread-5) FINISH, ConnectStoragePoolVDSCommand, log id: 471a71a1 >2014-07-14 18:52:38,616 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-6) [65935655] ActivateStorage Domain. After change storage pool status in vds. Time:7/14/14 6:52 PM >2014-07-14 18:52:38,639 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-6) [65935655] Correlation ID: 27a7104a, Call Stack: null, Custom Event ID: -1, Message: Storage Domain data2 (Data Center dc34) was activated by admin >2014-07-14 18:52:38,643 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-6) [65935655] Correlation ID: 28c377f9, Job ID: cd2181e4-24d9-4130-b862-cbd09cdd6cfd, Call Stack: null, Custom Event ID: -1, Message: Storage Domains were attached to Data Center dc34 by admin >2014-07-14 18:52:38,662 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-6) [65935655] Correlation ID: 3c188d70, Job ID: cd2181e4-24d9-4130-b862-cbd09cdd6cfd, Call Stack: null, Custom Event ID: -1, Message: Storage Domain data2 was attached to Data Center dc34 by admin >2014-07-14 18:52:46,441 INFO [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (http--0.0.0.0-8080-1) [21928f20] Lock Acquired to object EngineLock [exclusiveLocks= key: vm2 value: VM_NAME >, sharedLocks= ] >2014-07-14 18:52:46,463 INFO [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (http--0.0.0.0-8080-1) [21928f20] Running command: AddVmFromScratchCommand internal: false. Entities affected : ID: ffd463b3-b3aa-4f3d-b1f0-3fa0a1845ef9 Type: VdsGroups >2014-07-14 18:52:46,511 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [21928f20] Correlation ID: 21928f20, Job ID: d6ca0ead-1d77-4f92-909e-445375f49392, Call Stack: null, Custom Event ID: -1, Message: VM vm2 was created by admin. >2014-07-14 18:52:46,516 INFO [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (http--0.0.0.0-8080-1) [21928f20] Lock freed to object EngineLock [exclusiveLocks= key: vm2 value: VM_NAME >, sharedLocks= ] >2014-07-14 18:52:46,765 INFO [org.ovirt.engine.core.bll.network.vm.ReorderVmNicsCommand] (http--0.0.0.0-8080-1) [48cb5bd5] Running command: ReorderVmNicsCommand internal: false. Entities affected : ID: 1f884c23-5eac-46ea-aa28-6e6bb6dd925e Type: VM >2014-07-14 18:52:49,447 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (http--0.0.0.0-8080-1) [3c4ccbcd] Lock Acquired to object EngineLock [exclusiveLocks= key: 1f884c23-5eac-46ea-aa28-6e6bb6dd925e value: VM_DISK_BOOT >, sharedLocks= key: 1f884c23-5eac-46ea-aa28-6e6bb6dd925e value: VM >] >2014-07-14 18:52:49,470 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (http--0.0.0.0-8080-1) [3c4ccbcd] Running command: AddDiskCommand internal: false. Entities affected : ID: 1f884c23-5eac-46ea-aa28-6e6bb6dd925e Type: VM, ID: 79818395-80e1-40af-adb6-328223a20ad9 Type: Storage >2014-07-14 18:52:49,526 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (http--0.0.0.0-8080-1) [3df43e91] Running command: AddImageFromScratchCommand internal: true. Entities affected : ID: 79818395-80e1-40af-adb6-328223a20ad9 Type: Storage >2014-07-14 18:52:49,538 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (http--0.0.0.0-8080-1) [3df43e91] Lock freed to object EngineLock [exclusiveLocks= key: 1f884c23-5eac-46ea-aa28-6e6bb6dd925e value: VM_DISK_BOOT >, sharedLocks= key: 1f884c23-5eac-46ea-aa28-6e6bb6dd925e value: VM >] >2014-07-14 18:52:49,539 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (http--0.0.0.0-8080-1) [3df43e91] START, CreateImageVDSCommand( storagePoolId = 68055dba-2ea1-4ff1-864e-7c15d4b1334c, ignoreFailoverLimit = false, storageDomainId = 79818395-80e1-40af-adb6-328223a20ad9, imageGroupId = 57708ce1-c83a-44d4-914c-c4d9c48fdde6, imageSizeInBytes = 1073741824, volumeFormat = RAW, newImageId = a2a75a21-c4ee-4781-82c4-9aa534036fff, newImageDescription = ), log id: 93896d4 >2014-07-14 18:52:49,542 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (http--0.0.0.0-8080-1) [3df43e91] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID >2014-07-14 18:52:49,596 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (http--0.0.0.0-8080-1) [3df43e91] FINISH, CreateImageVDSCommand, return: a2a75a21-c4ee-4781-82c4-9aa534036fff, log id: 93896d4 >2014-07-14 18:52:49,603 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (http--0.0.0.0-8080-1) [3df43e91] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command f9434927-d192-45ca-b793-d2785645d3b7 >2014-07-14 18:52:49,603 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (http--0.0.0.0-8080-1) [3df43e91] CommandMultiAsyncTasks::AttachTask: Attaching task c4b23c1c-6195-428f-b03f-e308c110800e to command f9434927-d192-45ca-b793-d2785645d3b7. >2014-07-14 18:52:49,616 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (http--0.0.0.0-8080-1) [3df43e91] Adding task c4b23c1c-6195-428f-b03f-e308c110800e (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2014-07-14 18:52:49,651 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [3df43e91] Correlation ID: 3c4ccbcd, Job ID: f7fb8b8c-3b82-40bb-baa4-49f6ed0002d0, Call Stack: null, Custom Event ID: -1, Message: Add-Disk operation of vm2_Disk1 was initiated on VM vm2 by admin. >2014-07-14 18:52:49,652 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (http--0.0.0.0-8080-1) [3df43e91] BaseAsyncTask::startPollingTask: Starting to poll task c4b23c1c-6195-428f-b03f-e308c110800e. >2014-07-14 18:52:57,886 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-85) Polling and updating Async Tasks: 2 tasks, 1 tasks to poll now >2014-07-14 18:52:57,893 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-85) SPMAsyncTask::PollTask: Polling task c4b23c1c-6195-428f-b03f-e308c110800e (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2014-07-14 18:52:57,900 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-85) BaseAsyncTask::OnTaskEndSuccess: Task c4b23c1c-6195-428f-b03f-e308c110800e (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2014-07-14 18:52:57,901 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-85) CommandAsyncTask::EndActionIfNecessary: All tasks of command f9434927-d192-45ca-b793-d2785645d3b7 has ended -> executing endAction >2014-07-14 18:52:57,901 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-85) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: f9434927-d192-45ca-b793-d2785645d3b7): calling endAction . >2014-07-14 18:52:57,902 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-43) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction AddDisk, executionIndex: 0 >2014-07-14 18:52:57,924 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (org.ovirt.thread.pool-8-thread-43) [3c4ccbcd] Ending command successfully: org.ovirt.engine.core.bll.AddDiskCommand >2014-07-14 18:52:57,944 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (org.ovirt.thread.pool-8-thread-43) [3df43e91] Ending command successfully: org.ovirt.engine.core.bll.AddImageFromScratchCommand >2014-07-14 18:52:57,948 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-43) [3df43e91] START, GetImageInfoVDSCommand( storagePoolId = 68055dba-2ea1-4ff1-864e-7c15d4b1334c, ignoreFailoverLimit = false, storageDomainId = 79818395-80e1-40af-adb6-328223a20ad9, imageGroupId = 57708ce1-c83a-44d4-914c-c4d9c48fdde6, imageId = a2a75a21-c4ee-4781-82c4-9aa534036fff), log id: 7ee2cbd5 >2014-07-14 18:52:57,968 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-43) [3df43e91] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@e5fbe029, log id: 7ee2cbd5 >2014-07-14 18:52:57,990 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-43) Correlation ID: 3c4ccbcd, Call Stack: null, Custom Event ID: -1, Message: The disk vm2_Disk1 was successfully added to VM vm2. >2014-07-14 18:52:57,991 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-43) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk completed, handling the result. >2014-07-14 18:52:57,991 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-43) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type AddDisk succeeded, clearing tasks. >2014-07-14 18:52:57,995 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-43) SPMAsyncTask::ClearAsyncTask: Attempting to clear task c4b23c1c-6195-428f-b03f-e308c110800e >2014-07-14 18:52:57,995 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-43) START, SPMClearTaskVDSCommand( storagePoolId = 68055dba-2ea1-4ff1-864e-7c15d4b1334c, ignoreFailoverLimit = false, taskId = c4b23c1c-6195-428f-b03f-e308c110800e), log id: 20e0c043 >2014-07-14 18:52:57,996 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-43) START, HSMClearTaskVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, taskId=c4b23c1c-6195-428f-b03f-e308c110800e), log id: 36e7f92 >2014-07-14 18:52:58,009 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-43) FINISH, HSMClearTaskVDSCommand, log id: 36e7f92 >2014-07-14 18:52:58,009 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-43) FINISH, SPMClearTaskVDSCommand, log id: 20e0c043 >2014-07-14 18:52:58,020 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-43) BaseAsyncTask::RemoveTaskFromDB: Removed task c4b23c1c-6195-428f-b03f-e308c110800e from DataBase >2014-07-14 18:52:58,020 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-43) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity f9434927-d192-45ca-b793-d2785645d3b7 >2014-07-14 18:53:19,126 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (http--0.0.0.0-8080-1) [4bd5ac2f] Lock Acquired to object EngineLock [exclusiveLocks= key: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data3 value: STORAGE_CONNECTION >, sharedLocks= ] >2014-07-14 18:53:19,147 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (http--0.0.0.0-8080-1) [4bd5ac2f] Running command: AddStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:53:19,148 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [4bd5ac2f] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data3, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 2386adb >2014-07-14 18:53:19,235 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [4bd5ac2f] FINISH, ConnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 2386adb >2014-07-14 18:53:19,255 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (http--0.0.0.0-8080-1) [4bd5ac2f] Lock freed to object EngineLock [exclusiveLocks= key: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data3 value: STORAGE_CONNECTION >, sharedLocks= ] >2014-07-14 18:53:19,306 INFO [org.ovirt.engine.core.bll.storage.AddNFSStorageDomainCommand] (http--0.0.0.0-8080-1) [5f5087c0] Running command: AddNFSStorageDomainCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:53:19,317 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [5f5087c0] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 562abf05-0385-428e-b6e8-79b090698188, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data3, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 15660fcd >2014-07-14 18:53:19,337 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [5f5087c0] FINISH, ConnectStorageServerVDSCommand, return: {562abf05-0385-428e-b6e8-79b090698188=0}, log id: 15660fcd >2014-07-14 18:53:19,339 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (http--0.0.0.0-8080-1) [5f5087c0] START, CreateStorageDomainVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storageDomain=org.ovirt.engine.core.common.businessentities.StorageDomainStatic@d9aa4106, args=10.35.16.25:/volumes/smamit/frontend/gchaplik/data3), log id: 64642d6 >2014-07-14 18:53:20,446 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (http--0.0.0.0-8080-1) [5f5087c0] FINISH, CreateStorageDomainVDSCommand, log id: 64642d6 >2014-07-14 18:53:20,447 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (http--0.0.0.0-8080-1) [5f5087c0] START, GetStorageDomainStatsVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storageDomainId=d3b42b81-9524-42a9-9ebf-f54b5e9bef6b), log id: 9aa2946 >2014-07-14 18:53:20,471 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (http--0.0.0.0-8080-1) [5f5087c0] FINISH, GetStorageDomainStatsVDSCommand, return: org.ovirt.engine.core.common.businessentities.StorageDomain@9b8b4b2b, log id: 9aa2946 >2014-07-14 18:53:20,495 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [5f5087c0] Correlation ID: 5f5087c0, Job ID: 9019718c-f8a2-4d4f-803f-e9e6c8f2a2c7, Call Stack: null, Custom Event ID: -1, Message: Storage Domain data3 was added by admin >2014-07-14 18:53:20,561 INFO [org.ovirt.engine.core.bll.storage.DisconnectStorageServerConnectionCommand] (http--0.0.0.0-8080-1) [10e8b2d0] Running command: DisconnectStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:53:20,563 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [10e8b2d0] START, DisconnectStorageServerVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 562abf05-0385-428e-b6e8-79b090698188, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data3, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 13272eb6 >2014-07-14 18:53:20,689 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [10e8b2d0] FINISH, DisconnectStorageServerVDSCommand, return: {562abf05-0385-428e-b6e8-79b090698188=0}, log id: 13272eb6 >2014-07-14 18:53:20,791 INFO [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] (http--0.0.0.0-8080-1) [1807ee42] Running command: AttachStorageDomainToPoolCommand internal: false. Entities affected : ID: d3b42b81-9524-42a9-9ebf-f54b5e9bef6b Type: Storage >2014-07-14 18:53:20,799 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (org.ovirt.thread.pool-8-thread-43) [33752eb4] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:53:20,801 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-43) [33752eb4] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 562abf05-0385-428e-b6e8-79b090698188, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data3, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 41c2a0d9 >2014-07-14 18:53:20,885 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-43) [33752eb4] FINISH, ConnectStorageServerVDSCommand, return: {562abf05-0385-428e-b6e8-79b090698188=0}, log id: 41c2a0d9 >2014-07-14 18:53:20,890 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetStorageDomainInfoVDSCommand] (http--0.0.0.0-8080-1) [1807ee42] START, HSMGetStorageDomainInfoVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storageDomainId=d3b42b81-9524-42a9-9ebf-f54b5e9bef6b), log id: 13ad292b >2014-07-14 18:53:20,990 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetStorageDomainInfoVDSCommand] (http--0.0.0.0-8080-1) [1807ee42] FINISH, HSMGetStorageDomainInfoVDSCommand, return: <org.ovirt.engine.core.common.businessentities.StorageDomainStatic@d795bae7, null>, log id: 13ad292b >2014-07-14 18:53:20,992 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (http--0.0.0.0-8080-1) [1807ee42] START, AttachStorageDomainVDSCommand( storagePoolId = 68055dba-2ea1-4ff1-864e-7c15d4b1334c, ignoreFailoverLimit = false, storageDomainId = d3b42b81-9524-42a9-9ebf-f54b5e9bef6b), log id: 76d09f67 >2014-07-14 18:53:37,711 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (http--0.0.0.0-8080-5) [506247eb] Lock Acquired to object EngineLock [exclusiveLocks= key: 10.35.16.25:/volumes/smamit/frontend/gchaplik/import1 value: STORAGE_CONNECTION >, sharedLocks= ] >2014-07-14 18:53:37,719 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (http--0.0.0.0-8080-5) [506247eb] Running command: AddStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:53:37,720 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-5) [506247eb] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/import1, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 6bb11da2 >2014-07-14 18:53:37,789 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-5) [506247eb] FINISH, ConnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 6bb11da2 >2014-07-14 18:53:37,806 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (http--0.0.0.0-8080-5) [506247eb] Lock freed to object EngineLock [exclusiveLocks= key: 10.35.16.25:/volumes/smamit/frontend/gchaplik/import1 value: STORAGE_CONNECTION >, sharedLocks= ] >2014-07-14 18:53:37,851 INFO [org.ovirt.engine.core.bll.storage.AddNFSStorageDomainCommand] (http--0.0.0.0-8080-5) [4b45b8c7] Running command: AddNFSStorageDomainCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:53:37,856 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-5) [4b45b8c7] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 9dd7924c-082d-4cc7-9aef-d623c8d2147a, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/import1, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 734a26d5 >2014-07-14 18:53:37,873 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-5) [4b45b8c7] FINISH, ConnectStorageServerVDSCommand, return: {9dd7924c-082d-4cc7-9aef-d623c8d2147a=0}, log id: 734a26d5 >2014-07-14 18:53:37,875 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (http--0.0.0.0-8080-5) [4b45b8c7] START, CreateStorageDomainVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storageDomain=org.ovirt.engine.core.common.businessentities.StorageDomainStatic@13652b93, args=10.35.16.25:/volumes/smamit/frontend/gchaplik/import1), log id: 251fad0d >2014-07-14 18:53:38,420 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (http--0.0.0.0-8080-5) [4b45b8c7] FINISH, CreateStorageDomainVDSCommand, log id: 251fad0d >2014-07-14 18:53:38,421 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (http--0.0.0.0-8080-5) [4b45b8c7] START, GetStorageDomainStatsVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storageDomainId=605483d7-c792-48c0-a5d4-30985f2792d5), log id: f6c47ce >2014-07-14 18:53:38,522 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (http--0.0.0.0-8080-5) [4b45b8c7] FINISH, GetStorageDomainStatsVDSCommand, return: org.ovirt.engine.core.common.businessentities.StorageDomain@bd63ad47, log id: f6c47ce >2014-07-14 18:53:38,540 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-5) [4b45b8c7] Correlation ID: 4b45b8c7, Job ID: 4f081f69-ffad-40c0-ae55-5cdc0b438cc3, Call Stack: null, Custom Event ID: -1, Message: Storage Domain export was added by admin >2014-07-14 18:53:38,585 INFO [org.ovirt.engine.core.bll.storage.DisconnectStorageServerConnectionCommand] (http--0.0.0.0-8080-5) [2b7dcde0] Running command: DisconnectStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:53:38,587 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (http--0.0.0.0-8080-5) [2b7dcde0] START, DisconnectStorageServerVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 9dd7924c-082d-4cc7-9aef-d623c8d2147a, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/import1, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 79689a63 >2014-07-14 18:53:38,675 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (http--0.0.0.0-8080-5) [2b7dcde0] FINISH, DisconnectStorageServerVDSCommand, return: {9dd7924c-082d-4cc7-9aef-d623c8d2147a=0}, log id: 79689a63 >2014-07-14 18:53:38,760 INFO [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] (http--0.0.0.0-8080-5) [67bd626f] Running command: AttachStorageDomainToPoolCommand internal: false. Entities affected : ID: 605483d7-c792-48c0-a5d4-30985f2792d5 Type: Storage >2014-07-14 18:53:38,768 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (org.ovirt.thread.pool-8-thread-49) [5b576513] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:53:38,771 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-49) [5b576513] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 9dd7924c-082d-4cc7-9aef-d623c8d2147a, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/import1, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 70eed0c0 >2014-07-14 18:53:38,838 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-49) [5b576513] FINISH, ConnectStorageServerVDSCommand, return: {9dd7924c-082d-4cc7-9aef-d623c8d2147a=0}, log id: 70eed0c0 >2014-07-14 18:53:38,839 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (http--0.0.0.0-8080-5) [67bd626f] START, AttachStorageDomainVDSCommand( storagePoolId = 1acf6a79-0571-4d13-921c-d49ef1e0d9ec, ignoreFailoverLimit = false, storageDomainId = 605483d7-c792-48c0-a5d4-30985f2792d5), log id: 106761ac >2014-07-14 18:53:45,838 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (http--0.0.0.0-8080-1) [1807ee42] FINISH, AttachStorageDomainVDSCommand, log id: 76d09f67 >2014-07-14 18:53:45,865 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImagesListVDSCommand] (http--0.0.0.0-8080-1) [1807ee42] START, GetImagesListVDSCommand( storagePoolId = 68055dba-2ea1-4ff1-864e-7c15d4b1334c, ignoreFailoverLimit = false, sdUUID = d3b42b81-9524-42a9-9ebf-f54b5e9bef6b), log id: 1a4de5c9 >2014-07-14 18:53:45,881 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImagesListVDSCommand] (http--0.0.0.0-8080-1) [1807ee42] FINISH, GetImagesListVDSCommand, return: [], log id: 1a4de5c9 >2014-07-14 18:53:45,895 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) Correlation ID: 1807ee42, Job ID: 3dbf2622-2013-4a9b-8f6a-eebccf102c1b, Call Stack: null, Custom Event ID: -1, Message: Failed to retrieve VMs and Templates from the OVF disk of Storage Domain data3. >2014-07-14 18:53:45,904 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [2ac8c765] Lock Acquired to object EngineLock [exclusiveLocks= key: d3b42b81-9524-42a9-9ebf-f54b5e9bef6b value: STORAGE >, sharedLocks= ] >2014-07-14 18:53:45,910 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [2ac8c765] Running command: ActivateStorageDomainCommand internal: true. Entities affected : ID: d3b42b81-9524-42a9-9ebf-f54b5e9bef6b Type: Storage >2014-07-14 18:53:45,914 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [2ac8c765] Lock freed to object EngineLock [exclusiveLocks= key: d3b42b81-9524-42a9-9ebf-f54b5e9bef6b value: STORAGE >, sharedLocks= ] >2014-07-14 18:53:45,914 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [2ac8c765] ActivateStorage Domain. Before Connect all hosts to pool. Time:7/14/14 6:53 PM >2014-07-14 18:53:45,917 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (org.ovirt.thread.pool-8-thread-14) [1486610f] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:53:45,918 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-14) [1486610f] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 562abf05-0385-428e-b6e8-79b090698188, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/data3, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 166d5f0c >2014-07-14 18:53:45,934 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-14) [1486610f] FINISH, ConnectStorageServerVDSCommand, return: {562abf05-0385-428e-b6e8-79b090698188=0}, log id: 166d5f0c >2014-07-14 18:53:45,935 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (http--0.0.0.0-8080-1) [2ac8c765] START, ActivateStorageDomainVDSCommand( storagePoolId = 68055dba-2ea1-4ff1-864e-7c15d4b1334c, ignoreFailoverLimit = false, storageDomainId = d3b42b81-9524-42a9-9ebf-f54b5e9bef6b), log id: 4101630b >2014-07-14 18:53:46,087 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (http--0.0.0.0-8080-1) [2ac8c765] FINISH, ActivateStorageDomainVDSCommand, log id: 4101630b >2014-07-14 18:53:46,087 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [2ac8c765] ActivateStorage Domain. After Connect all hosts to pool. Time:7/14/14 6:53 PM >2014-07-14 18:53:46,100 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-8-thread-20) START, ConnectStoragePoolVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, vdsId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 68055dba-2ea1-4ff1-864e-7c15d4b1334c, masterVersion = 1), log id: 9d72ca5 >2014-07-14 18:53:46,108 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-8-thread-20) FINISH, ConnectStoragePoolVDSCommand, log id: 9d72ca5 >2014-07-14 18:53:46,108 INFO [org.ovirt.engine.core.bll.storage.RefreshPoolSingleAsyncOperation] (org.ovirt.thread.pool-8-thread-20) Refreshed vds 10.35.4.198 in pool dc34 >2014-07-14 18:53:46,109 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [2ac8c765] ActivateStorage Domain. After change storage pool status in vds. Time:7/14/14 6:53 PM >2014-07-14 18:53:46,123 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [2ac8c765] Correlation ID: 2ac8c765, Call Stack: null, Custom Event ID: -1, Message: Storage Domain data3 (Data Center dc34) was activated by Non interactive user >2014-07-14 18:53:46,139 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [2ac8c765] Correlation ID: 1807ee42, Job ID: 3dbf2622-2013-4a9b-8f6a-eebccf102c1b, Call Stack: null, Custom Event ID: -1, Message: Storage Domain data3 was attached to Data Center dc34 by admin >2014-07-14 18:53:57,853 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-59) Setting new tasks map. The map contains now 1 tasks >2014-07-14 18:53:57,853 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-59) Cleared all tasks of pool 1acf6a79-0571-4d13-921c-d49ef1e0d9ec. >2014-07-14 18:53:58,404 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (http--0.0.0.0-8080-1) [26328d2d] Lock Acquired to object EngineLock [exclusiveLocks= key: 10.35.16.25:/volumes/smamit/frontend/gchaplik/import2 value: STORAGE_CONNECTION >, sharedLocks= ] >2014-07-14 18:53:58,424 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (http--0.0.0.0-8080-1) [26328d2d] Running command: AddStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:53:58,447 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [26328d2d] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/import2, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 55b0b495 >2014-07-14 18:53:58,536 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [26328d2d] FINISH, ConnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 55b0b495 >2014-07-14 18:53:58,560 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (http--0.0.0.0-8080-1) [26328d2d] Lock freed to object EngineLock [exclusiveLocks= key: 10.35.16.25:/volumes/smamit/frontend/gchaplik/import2 value: STORAGE_CONNECTION >, sharedLocks= ] >2014-07-14 18:53:58,604 INFO [org.ovirt.engine.core.bll.storage.AddNFSStorageDomainCommand] (http--0.0.0.0-8080-1) [1cd78037] Running command: AddNFSStorageDomainCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:53:58,616 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [1cd78037] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 4a80243e-01c3-4127-9989-f26cda187f74, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/import2, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 747b15eb >2014-07-14 18:53:58,634 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [1cd78037] FINISH, ConnectStorageServerVDSCommand, return: {4a80243e-01c3-4127-9989-f26cda187f74=0}, log id: 747b15eb >2014-07-14 18:53:58,635 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (http--0.0.0.0-8080-1) [1cd78037] START, CreateStorageDomainVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storageDomain=org.ovirt.engine.core.common.businessentities.StorageDomainStatic@f9aae23e, args=10.35.16.25:/volumes/smamit/frontend/gchaplik/import2), log id: 47f639f0 >2014-07-14 18:53:59,034 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (http--0.0.0.0-8080-1) [1cd78037] FINISH, CreateStorageDomainVDSCommand, log id: 47f639f0 >2014-07-14 18:53:59,035 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (http--0.0.0.0-8080-1) [1cd78037] START, GetStorageDomainStatsVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storageDomainId=64ac05f8-cdbf-499a-9c0d-2c3d045bddca), log id: 32e39769 >2014-07-14 18:53:59,603 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (http--0.0.0.0-8080-1) [1cd78037] FINISH, GetStorageDomainStatsVDSCommand, return: org.ovirt.engine.core.common.businessentities.StorageDomain@9d3982f1, log id: 32e39769 >2014-07-14 18:53:59,627 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [1cd78037] Correlation ID: 1cd78037, Job ID: db2ae057-ce6b-4ed3-94b4-ef18e1f595a1, Call Stack: null, Custom Event ID: -1, Message: Storage Domain import2 was added by admin >2014-07-14 18:53:59,783 INFO [org.ovirt.engine.core.bll.storage.DisconnectStorageServerConnectionCommand] (http--0.0.0.0-8080-1) [62601b41] Running command: DisconnectStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:53:59,784 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [62601b41] START, DisconnectStorageServerVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 4a80243e-01c3-4127-9989-f26cda187f74, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/import2, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 6d35d110 >2014-07-14 18:53:59,876 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (http--0.0.0.0-8080-1) [62601b41] FINISH, DisconnectStorageServerVDSCommand, return: {4a80243e-01c3-4127-9989-f26cda187f74=0}, log id: 6d35d110 >2014-07-14 18:53:59,965 INFO [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] (http--0.0.0.0-8080-1) [27002831] Running command: AttachStorageDomainToPoolCommand internal: false. Entities affected : ID: 64ac05f8-cdbf-499a-9c0d-2c3d045bddca Type: Storage >2014-07-14 18:53:59,977 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (org.ovirt.thread.pool-8-thread-4) [368fcf51] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:53:59,980 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-4) [368fcf51] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 4a80243e-01c3-4127-9989-f26cda187f74, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/import2, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 51ccc58b >2014-07-14 18:54:00,051 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (http--0.0.0.0-8080-5) [67bd626f] FINISH, AttachStorageDomainVDSCommand, log id: 106761ac >2014-07-14 18:54:00,054 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImagesListVDSCommand] (http--0.0.0.0-8080-5) [67bd626f] START, GetImagesListVDSCommand( storagePoolId = 1acf6a79-0571-4d13-921c-d49ef1e0d9ec, ignoreFailoverLimit = false, sdUUID = 605483d7-c792-48c0-a5d4-30985f2792d5), log id: 39d72683 >2014-07-14 18:54:00,063 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-4) [368fcf51] FINISH, ConnectStorageServerVDSCommand, return: {4a80243e-01c3-4127-9989-f26cda187f74=0}, log id: 51ccc58b >2014-07-14 18:54:00,064 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (http--0.0.0.0-8080-1) [27002831] START, AttachStorageDomainVDSCommand( storagePoolId = 68055dba-2ea1-4ff1-864e-7c15d4b1334c, ignoreFailoverLimit = false, storageDomainId = 64ac05f8-cdbf-499a-9c0d-2c3d045bddca), log id: 126f80d0 >2014-07-14 18:54:00,100 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImagesListVDSCommand] (http--0.0.0.0-8080-5) [67bd626f] FINISH, GetImagesListVDSCommand, return: [], log id: 39d72683 >2014-07-14 18:54:00,106 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-5) Correlation ID: 67bd626f, Job ID: 7bab2099-4dd0-4aad-a5e8-4a143eee9e0d, Call Stack: null, Custom Event ID: -1, Message: Failed to retrieve VMs and Templates from the OVF disk of Storage Domain export. >2014-07-14 18:54:00,116 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-5) [3bc5a6bf] Lock Acquired to object EngineLock [exclusiveLocks= key: 605483d7-c792-48c0-a5d4-30985f2792d5 value: STORAGE >, sharedLocks= ] >2014-07-14 18:54:00,121 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-5) [3bc5a6bf] Running command: ActivateStorageDomainCommand internal: true. Entities affected : ID: 605483d7-c792-48c0-a5d4-30985f2792d5 Type: Storage >2014-07-14 18:54:00,127 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-5) [3bc5a6bf] Lock freed to object EngineLock [exclusiveLocks= key: 605483d7-c792-48c0-a5d4-30985f2792d5 value: STORAGE >, sharedLocks= ] >2014-07-14 18:54:00,128 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-5) [3bc5a6bf] ActivateStorage Domain. Before Connect all hosts to pool. Time:7/14/14 6:54 PM >2014-07-14 18:54:00,131 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (org.ovirt.thread.pool-8-thread-13) [79d411f7] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:54:00,132 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-13) [79d411f7] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 9dd7924c-082d-4cc7-9aef-d623c8d2147a, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/import1, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 43b7abd1 >2014-07-14 18:54:00,190 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-13) [79d411f7] FINISH, ConnectStorageServerVDSCommand, return: {9dd7924c-082d-4cc7-9aef-d623c8d2147a=0}, log id: 43b7abd1 >2014-07-14 18:54:00,195 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (http--0.0.0.0-8080-5) [3bc5a6bf] START, ActivateStorageDomainVDSCommand( storagePoolId = 1acf6a79-0571-4d13-921c-d49ef1e0d9ec, ignoreFailoverLimit = false, storageDomainId = 605483d7-c792-48c0-a5d4-30985f2792d5), log id: 351d7934 >2014-07-14 18:54:00,362 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (http--0.0.0.0-8080-5) [3bc5a6bf] FINISH, ActivateStorageDomainVDSCommand, log id: 351d7934 >2014-07-14 18:54:00,362 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-5) [3bc5a6bf] ActivateStorage Domain. After Connect all hosts to pool. Time:7/14/14 6:54 PM >2014-07-14 18:54:00,377 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-8-thread-16) START, ConnectStoragePoolVDSCommand(HostName = 10.35.4.87, HostId = 137a97e4-82bd-4c84-8fff-5837e41c5287, vdsId = 137a97e4-82bd-4c84-8fff-5837e41c5287, storagePoolId = 1acf6a79-0571-4d13-921c-d49ef1e0d9ec, masterVersion = 1), log id: 14686745 >2014-07-14 18:54:00,417 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-8-thread-16) FINISH, ConnectStoragePoolVDSCommand, log id: 14686745 >2014-07-14 18:54:00,417 INFO [org.ovirt.engine.core.bll.storage.RefreshPoolSingleAsyncOperation] (org.ovirt.thread.pool-8-thread-16) Refreshed vds 10.35.4.87 in pool dc33 >2014-07-14 18:54:00,419 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-5) [3bc5a6bf] ActivateStorage Domain. After change storage pool status in vds. Time:7/14/14 6:54 PM >2014-07-14 18:54:00,430 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-5) [3bc5a6bf] Correlation ID: 3bc5a6bf, Call Stack: null, Custom Event ID: -1, Message: Storage Domain export (Data Center dc33) was activated by Non interactive user >2014-07-14 18:54:00,447 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-5) [3bc5a6bf] Correlation ID: 67bd626f, Job ID: 7bab2099-4dd0-4aad-a5e8-4a143eee9e0d, Call Stack: null, Custom Event ID: -1, Message: Storage Domain export was attached to Data Center dc33 by admin >2014-07-14 18:54:21,373 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (http--0.0.0.0-8080-1) [27002831] FINISH, AttachStorageDomainVDSCommand, log id: 126f80d0 >2014-07-14 18:54:21,376 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImagesListVDSCommand] (http--0.0.0.0-8080-1) [27002831] START, GetImagesListVDSCommand( storagePoolId = 68055dba-2ea1-4ff1-864e-7c15d4b1334c, ignoreFailoverLimit = false, sdUUID = 64ac05f8-cdbf-499a-9c0d-2c3d045bddca), log id: 43872559 >2014-07-14 18:54:21,442 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImagesListVDSCommand] (http--0.0.0.0-8080-1) [27002831] FINISH, GetImagesListVDSCommand, return: [], log id: 43872559 >2014-07-14 18:54:21,465 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) Correlation ID: 27002831, Job ID: 85338c49-67a9-479d-9de8-b615e028cd7e, Call Stack: null, Custom Event ID: -1, Message: Failed to retrieve VMs and Templates from the OVF disk of Storage Domain import2. >2014-07-14 18:54:21,470 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [2c016360] Lock Acquired to object EngineLock [exclusiveLocks= key: 64ac05f8-cdbf-499a-9c0d-2c3d045bddca value: STORAGE >, sharedLocks= ] >2014-07-14 18:54:21,478 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [2c016360] Running command: ActivateStorageDomainCommand internal: true. Entities affected : ID: 64ac05f8-cdbf-499a-9c0d-2c3d045bddca Type: Storage >2014-07-14 18:54:21,488 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [2c016360] Lock freed to object EngineLock [exclusiveLocks= key: 64ac05f8-cdbf-499a-9c0d-2c3d045bddca value: STORAGE >, sharedLocks= ] >2014-07-14 18:54:21,489 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [2c016360] ActivateStorage Domain. Before Connect all hosts to pool. Time:7/14/14 6:54 PM >2014-07-14 18:54:21,491 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (org.ovirt.thread.pool-8-thread-11) [6cdccfec] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2014-07-14 18:54:21,503 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-11) [6cdccfec] START, ConnectStorageServerVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 4a80243e-01c3-4127-9989-f26cda187f74, connection: 10.35.16.25:/volumes/smamit/frontend/gchaplik/import2, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 56eee0e7 >2014-07-14 18:54:21,519 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-8-thread-11) [6cdccfec] FINISH, ConnectStorageServerVDSCommand, return: {4a80243e-01c3-4127-9989-f26cda187f74=0}, log id: 56eee0e7 >2014-07-14 18:54:21,520 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (http--0.0.0.0-8080-1) [2c016360] START, ActivateStorageDomainVDSCommand( storagePoolId = 68055dba-2ea1-4ff1-864e-7c15d4b1334c, ignoreFailoverLimit = false, storageDomainId = 64ac05f8-cdbf-499a-9c0d-2c3d045bddca), log id: 279abb8e >2014-07-14 18:54:21,662 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (http--0.0.0.0-8080-1) [2c016360] FINISH, ActivateStorageDomainVDSCommand, log id: 279abb8e >2014-07-14 18:54:21,662 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [2c016360] ActivateStorage Domain. After Connect all hosts to pool. Time:7/14/14 6:54 PM >2014-07-14 18:54:21,676 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-8-thread-16) START, ConnectStoragePoolVDSCommand(HostName = 10.35.4.198, HostId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, vdsId = 716e77f8-7fc6-458d-95f9-2d99e4ca6868, storagePoolId = 68055dba-2ea1-4ff1-864e-7c15d4b1334c, masterVersion = 1), log id: 194d7d23 >2014-07-14 18:54:21,685 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-8-thread-16) FINISH, ConnectStoragePoolVDSCommand, log id: 194d7d23 >2014-07-14 18:54:21,685 INFO [org.ovirt.engine.core.bll.storage.RefreshPoolSingleAsyncOperation] (org.ovirt.thread.pool-8-thread-16) Refreshed vds 10.35.4.198 in pool dc34 >2014-07-14 18:54:21,685 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (http--0.0.0.0-8080-1) [2c016360] ActivateStorage Domain. After change storage pool status in vds. Time:7/14/14 6:54 PM >2014-07-14 18:54:21,696 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [2c016360] Correlation ID: 2c016360, Call Stack: null, Custom Event ID: -1, Message: Storage Domain import2 (Data Center dc34) was activated by Non interactive user >2014-07-14 18:54:21,710 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [2c016360] Correlation ID: 27002831, Job ID: 85338c49-67a9-479d-9de8-b615e028cd7e, Call Stack: null, Custom Event ID: -1, Message: Storage Domain import2 was attached to Data Center dc34 by admin >2014-07-14 18:54:27,855 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-51) Setting new tasks map. The map contains now 0 tasks >2014-07-14 18:54:27,855 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-51) Cleared all tasks of pool 68055dba-2ea1-4ff1-864e-7c15d4b1334c.
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=917903">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 1119376
:
917900
| 917903 |
917906
|
917907