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 878846 Details for
Bug 1080594
[host-deploy][node-upgarde] Pipe closed at the end of vdsm-upgrade
[?]
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
log_marc25_upgrade_timeout_and_pipe_closed.log (text/x-log), 102.17 KB, created by
Douglas Schilling Landgraf
on 2014-03-26 04:29:37 UTC
(
hide
)
Description:
engine.log
Filename:
MIME Type:
Creator:
Douglas Schilling Landgraf
Created:
2014-03-26 04:29:37 UTC
Size:
102.17 KB
patch
obsolete
> >2014-03-26 00:17:20,727 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Loaded file "/home/douglas/ovirt-engine/share/ovirt-engine/services/ovirt-engine/ovirt-engine.conf". >2014-03-26 00:17:20,729 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) The file "/home/douglas/ovirt-engine/etc/ovirt-engine/engine.conf" doesn't exist or isn't readable. Will return an empty set of properties. >2014-03-26 00:17:20,734 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Loaded file "/home/douglas/ovirt-engine/etc/ovirt-engine/engine.conf.d/10-setup-database.conf". >2014-03-26 00:17:20,735 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Loaded file "/home/douglas/ovirt-engine/etc/ovirt-engine/engine.conf.d/10-setup-jboss.conf". >2014-03-26 00:17:20,739 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Loaded file "/home/douglas/ovirt-engine/etc/ovirt-engine/engine.conf.d/10-setup-pki.conf". >2014-03-26 00:17:20,744 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Loaded file "/home/douglas/ovirt-engine/etc/ovirt-engine/engine.conf.d/10-setup-protocols.conf". >2014-03-26 00:17:20,746 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_AJP_ENABLED" is "false". >2014-03-26 00:17:20,749 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_AJP_PORT" is "None". >2014-03-26 00:17:20,751 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_APPS" is "engine.ear". >2014-03-26 00:17:20,752 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_CACHE" is "/home/douglas/ovirt-engine/var/cache/ovirt-engine". >2014-03-26 00:17:20,754 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_DB_CHECK_INTERVAL" is "1000". >2014-03-26 00:17:20,759 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_DB_CONNECTION_TIMEOUT" is "300000". >2014-03-26 00:17:20,760 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_DB_DATABASE" is "engine". >2014-03-26 00:17:20,769 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_DB_DRIVER" is "org.postgresql.Driver". >2014-03-26 00:17:20,779 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_DB_HOST" is "localhost". >2014-03-26 00:17:20,782 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_DB_MAX_CONNECTIONS" is "100". >2014-03-26 00:17:20,784 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_DB_MIN_CONNECTIONS" is "1". >2014-03-26 00:17:20,787 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_DB_PASSWORD" is "***". >2014-03-26 00:17:20,789 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_DB_PORT" is "5432". >2014-03-26 00:17:20,790 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_DB_SECURED" is "False". >2014-03-26 00:17:20,806 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_DB_SECURED_VALIDATION" is "False". >2014-03-26 00:17:20,808 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_DB_URL" is "jdbc:postgresql://localhost:5432/engine?sslfactory=org.postgresql.ssl.NonValidatingFactory". >2014-03-26 00:17:20,810 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_DB_USER" is "engine". >2014-03-26 00:17:20,811 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_DEBUG_ADDRESS" is "127.0.0.1:8787". >2014-03-26 00:17:20,811 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_DOC" is "/home/douglas/ovirt-engine/share/doc/ovirt-engine". >2014-03-26 00:17:20,812 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_ETC" is "/home/douglas/ovirt-engine/etc/ovirt-engine". >2014-03-26 00:17:20,813 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_FQDN" is "enginedevelvm.localdomain". >2014-03-26 00:17:20,814 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_GROUP" is "ovirt". >2014-03-26 00:17:20,818 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_HEAP_MAX" is "1g". >2014-03-26 00:17:20,819 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_HEAP_MIN" is "1g". >2014-03-26 00:17:20,820 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_HTTPS_ENABLED" is "true". >2014-03-26 00:17:20,821 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_HTTPS_PORT" is "8443". >2014-03-26 00:17:20,822 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_HTTPS_PROTOCOLS" is "SSLv3,TLSv1,TLSv1.1,TLSv1.2". >2014-03-26 00:17:20,823 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_HTTP_ENABLED" is "true". >2014-03-26 00:17:20,824 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_HTTP_PORT" is "8080". >2014-03-26 00:17:20,824 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_JAVA_MODULEPATH" is "/home/douglas/ovirt-engine/share/ovirt-engine/modules". >2014-03-26 00:17:20,828 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_JVM_ARGS" is " -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath="/home/douglas/ovirt-engine/var/log/ovirt-engine/dump"". >2014-03-26 00:17:20,830 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_LOG" is "/home/douglas/ovirt-engine/var/log/ovirt-engine". >2014-03-26 00:17:20,832 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_LOG_TO_CONSOLE" is "true". >2014-03-26 00:17:20,833 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_MANUAL" is "/home/douglas/ovirt-engine/share/ovirt-engine/manual". >2014-03-26 00:17:20,835 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_PERM_MAX" is "256m". >2014-03-26 00:17:20,837 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_PERM_MIN" is "256m". >2014-03-26 00:17:20,838 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_PKI" is "/home/douglas/ovirt-engine/etc/pki/ovirt-engine". >2014-03-26 00:17:20,839 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_PKI_CA" is "/home/douglas/ovirt-engine/etc/pki/ovirt-engine/ca.pem". >2014-03-26 00:17:20,841 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_PKI_ENGINE_CERT" is "/home/douglas/ovirt-engine/etc/pki/ovirt-engine/certs/engine.cer". >2014-03-26 00:17:20,843 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_PKI_ENGINE_STORE" is "/home/douglas/ovirt-engine/etc/pki/ovirt-engine/keys/engine.p12". >2014-03-26 00:17:20,844 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_PKI_ENGINE_STORE_ALIAS" is "1". >2014-03-26 00:17:20,845 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_PKI_ENGINE_STORE_PASSWORD" is "***". >2014-03-26 00:17:20,847 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_PKI_TRUST_STORE" is "/home/douglas/ovirt-engine/etc/pki/ovirt-engine/.truststore". >2014-03-26 00:17:20,848 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_PKI_TRUST_STORE_PASSWORD" is "***". >2014-03-26 00:17:20,849 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_PROPERTIES" is " jsse.enableSNIExtension=false". >2014-03-26 00:17:20,850 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_PROXY_ENABLED" is "false". >2014-03-26 00:17:20,851 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_PROXY_HTTPS_PORT" is "443". >2014-03-26 00:17:20,851 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_PROXY_HTTP_PORT" is "80". >2014-03-26 00:17:20,852 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_REPORTS_UI" is "/home/douglas/ovirt-engine/var/lib/ovirt-engine/reports.xml". >2014-03-26 00:17:20,853 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_STOP_INTERVAL" is "1". >2014-03-26 00:17:20,854 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_STOP_TIME" is "10". >2014-03-26 00:17:20,855 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_TMP" is "/home/douglas/ovirt-engine/var/tmp/ovirt-engine". >2014-03-26 00:17:20,856 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_UP_MARK" is "/home/douglas/ovirt-engine/var/lib/ovirt-engine/engine.up". >2014-03-26 00:17:20,857 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_URI" is "/ovirt-engine". >2014-03-26 00:17:20,859 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_USER" is "ovirt". >2014-03-26 00:17:20,860 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_USR" is "/home/douglas/ovirt-engine/share/ovirt-engine". >2014-03-26 00:17:20,864 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_VAR" is "/home/douglas/ovirt-engine/var/lib/ovirt-engine". >2014-03-26 00:17:20,865 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "ENGINE_VERBOSE_GC" is "false". >2014-03-26 00:17:20,866 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "JBOSS_HOME" is "/usr/share/jboss-as". >2014-03-26 00:17:20,866 INFO [org.ovirt.engine.core.utils.LocalConfig] (MSC service thread 1-2) Value of property "SENSITIVE_KEYS" is ",ENGINE_DB_PASSWORD,ENGINE_PKI_TRUST_STORE_PASSWORD,ENGINE_PKI_ENGINE_STORE_PASSWORD". >2014-03-26 00:17:21,281 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) Start initializing Backend >2014-03-26 00:17:21,498 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-5) Could not parse option AutoRecoveryAllowedTypes value. >2014-03-26 00:17:21,529 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) Running ovirt-engine 3.4.1_master >2014-03-26 00:17:21,531 INFO [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-5) Start initializing dictionaries >2014-03-26 00:17:21,536 INFO [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-5) Finished initializing dictionaries >2014-03-26 00:17:21,538 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-5) Start initializing AuditLogCleanupManager >2014-03-26 00:17:21,539 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-5) Setting audit cleanup manager to run at: 35 35 3 * * ? >2014-03-26 00:17:21,558 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-5) Finished initializing AuditLogCleanupManager >2014-03-26 00:17:21,562 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-5) Start initializing TagsDirector >2014-03-26 00:17:21,574 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-5) Tag root added to tree >2014-03-26 00:17:21,587 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-5) Finished initializing TagsDirector >2014-03-26 00:17:21,590 INFO [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-5) Start initializing IsoDomainListSyncronizer >2014-03-26 00:17:21,598 INFO [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-5) Finished initializing IsoDomainListSyncronizer >2014-03-26 00:17:21,610 INFO [org.ovirt.engine.core.utils.osinfo.OsInfoPreferencesLoader] (MSC service thread 1-5) Loaded file /home/douglas/ovirt-engine/etc/ovirt-engine/osinfo.conf.d/00-defaults.properties >2014-03-26 00:17:21,757 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) Completed initializing handlers >2014-03-26 00:17:21,759 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-5) Start initializing ErrorTranslatorImpl >2014-03-26 00:17:21,770 WARN [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-5) Code MAC_ADDRESS_IS_IN_USE appears more than once in string table. >2014-03-26 00:17:21,772 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-5) Finished initializing ErrorTranslatorImpl >2014-03-26 00:17:21,772 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-5) Start initializing ErrorTranslatorImpl >2014-03-26 00:17:21,773 INFO [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-5) Finished initializing ErrorTranslatorImpl >2014-03-26 00:17:21,774 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) Mark incomplete jobs as UNKNOWN >2014-03-26 00:17:21,799 INFO [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-5) Start initializing JobRepositoryCleanupManager >2014-03-26 00:17:21,800 INFO [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-5) Finished initializing JobRepositoryCleanupManager >2014-03-26 00:17:21,801 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-5) Start initializing AutoRecoveryManager >2014-03-26 00:17:21,803 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-5) Finished initializing AutoRecoveryManager >2014-03-26 00:17:21,804 INFO [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-5) Start initializing ExecutionMessageDirector >2014-03-26 00:17:21,806 INFO [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-5) Finished initializing ExecutionMessageDirector >2014-03-26 00:17:21,896 INFO [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-6) Start initializing UsersDomainsCacheManagerService >2014-03-26 00:17:21,904 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (MSC service thread 1-6) Start initializing DbUserCacheManager >2014-03-26 00:17:21,912 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (MSC service thread 1-6) Finished initializing DbUserCacheManager >2014-03-26 00:17:21,913 INFO [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-6) Finished initializing UsersDomainsCacheManagerService >2014-03-26 00:17:21,972 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (MSC service thread 1-4) Initialization of AsyncTaskManager completed successfully. >2014-03-26 00:17:21,974 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-4) Start initializing ResourceManager >2014-03-26 00:17:22,071 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-4) Entered VdsManager constructor >2014-03-26 00:17:22,100 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-4) Initialize vdsBroker (192.168.100.177,54,321) >2014-03-26 00:17:22,210 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-4) VDS a27b5b25-6c9e-49ee-87e5-8b8295ec326e was added to the Resource Manager >2014-03-26 00:17:22,226 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-4) Finished initializing ResourceManager >2014-03-26 00:17:22,232 INFO [org.ovirt.engine.core.bll.OvfDataUpdater] (MSC service thread 1-4) Initialization of OvfDataUpdater completed successfully. >2014-03-26 00:17:22,235 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-4) Start scheduling to enable vds load balancer >2014-03-26 00:17:22,236 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-4) Finished scheduling to enable vds load balancer >2014-03-26 00:17:22,237 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-4) Start HA Reservation check >2014-03-26 00:17:22,238 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-4) Finished HA Reservation check >2014-03-26 00:17:22,252 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-4) Init VM custom properties utilities >2014-03-26 00:17:22,254 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-4) Init device custom properties utilities >2014-03-26 00:17:22,254 INFO [org.ovirt.engine.core.bll.network.MacPoolManager] (org.ovirt.thread.pool-6-thread-1) MacPoolManager(7eea83ca): Start initializing >2014-03-26 00:17:22,259 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-4) Initializing Scheduling manager >2014-03-26 00:17:22,291 INFO [org.ovirt.engine.core.bll.network.MacPoolManager] (org.ovirt.thread.pool-6-thread-1) MacPoolManager(7eea83ca): Finished initializing. Available MACs in pool: 256 >2014-03-26 00:17:22,308 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-4) External scheduler disabled, discovery skipped >2014-03-26 00:17:22,308 INFO [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-4) Initialized Scheduling manager >2014-03-26 00:17:22,309 INFO [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-4) Initializing DWH Heart Beat >2014-03-26 00:17:22,311 INFO [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-4) DWH Heart Beat initialized >2014-03-26 00:17:23,975 INFO [org.ovirt.engine.core.bll.LogoutUserCommand] (http--0.0.0.0-8080-1) [20ae822f] Running command: LogoutUserCommand internal: false. >2014-03-26 00:17:24,016 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: UNASSIGNED not exist in string table >2014-03-26 00:17:24,017 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: USER_FAILED_REMOVE_VM not exist in string table >2014-03-26 00:17:24,018 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: USER_RUN_UNLOCK_ENTITY_SCRIPT not exist in string table >2014-03-26 00:17:24,019 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: VDS_NETWORK_MTU_DIFFER_FROM_LOGICAL_NETWORK not exist in string table >2014-03-26 00:17:24,021 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: STORAGE_ACTIVATE_ASYNC not exist in string table >2014-03-26 00:17:24,022 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: DWH_STOPPED not exist in string table >2014-03-26 00:17:24,023 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: DWH_STARTED not exist in string table >2014-03-26 00:17:24,023 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: DWH_ERROR not exist in string table >2014-03-26 00:17:24,031 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: USER_FAILED_REMOVE_VM not have severity. Assumed Normal >2014-03-26 00:17:24,032 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: USER_ATTACH_DISK_TO_VM not have severity. Assumed Normal >2014-03-26 00:17:24,033 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: USER_DETACH_DISK_FROM_VM not have severity. Assumed Normal >2014-03-26 00:17:24,034 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: USER_FAILED_DETACH_DISK_FROM_VM not have severity. Assumed Normal >2014-03-26 00:17:24,034 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: USER_RUN_UNLOCK_ENTITY_SCRIPT not have severity. Assumed Normal >2014-03-26 00:17:24,035 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: GLUSTER_VOLUME_OPTION_CHANGED_FROM_CLI not have severity. Assumed Normal >2014-03-26 00:17:24,036 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: GLUSTER_SERVICES_LIST_NOT_FETCHED not have severity. Assumed Normal >2014-03-26 00:17:24,037 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: GLUSTER_VOLUME_BRICK_ADDED not have severity. Assumed Normal >2014-03-26 00:17:24,038 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: USER_EXTEND_DISK_SIZE_UPDATE_VM_FAILURE not have severity. Assumed Normal >2014-03-26 00:17:24,039 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: VM_MIGRATION_START_SYSTEM_INITIATED not have severity. Assumed Normal >2014-03-26 00:17:24,040 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: VDS_NETWORK_MTU_DIFFER_FROM_LOGICAL_NETWORK not have severity. Assumed Normal >2014-03-26 00:17:24,041 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: NETWORK_UPDATE_VM_INTERFACE_LINK_UP not have severity. Assumed Normal >2014-03-26 00:17:24,041 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: NETWORK_UPDATE_VM_INTERFACE_LINK_DOWN not have severity. Assumed Normal >2014-03-26 00:17:24,043 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: USER_ADDED_AFFINITY_GROUP not have severity. Assumed Normal >2014-03-26 00:17:24,043 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: USER_FAILED_TO_ADD_AFFINITY_GROUP not have severity. Assumed Normal >2014-03-26 00:17:24,044 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: USER_UPDATED_AFFINITY_GROUP not have severity. Assumed Normal >2014-03-26 00:17:24,045 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: USER_FAILED_TO_UPDATE_AFFINITY_GROUP not have severity. Assumed Normal >2014-03-26 00:17:24,046 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: USER_REMOVED_AFFINITY_GROUP not have severity. Assumed Normal >2014-03-26 00:17:24,047 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] AuditLogType: USER_FAILED_TO_REMOVE_AFFINITY_GROUP not have severity. Assumed Normal >2014-03-26 00:17:24,086 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [20ae822f] Correlation ID: 20ae822f, Call Stack: null, Custom Event ID: -1, Message: User admin logged out. >2014-03-26 00:17:26,008 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-6) START, GetHardwareInfoVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]), log id: 4399c075 >2014-03-26 00:17:26,100 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-6) FINISH, GetHardwareInfoVDSCommand, log id: 4399c075 >2014-03-26 00:17:26,153 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: State was set to Up for host localhost.localdomain. >2014-03-26 00:17:26,257 INFO [org.ovirt.engine.core.bll.LoginAdminUserCommand] (http--0.0.0.0-8080-1) Running command: LoginAdminUserCommand internal: false. >2014-03-26 00:17:26,304 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-6) Initializing Host: localhost.localdomain >2014-03-26 00:17:26,329 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (DefaultQuartzScheduler_Worker-6) [1dc431a] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:17:26,424 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-03-26 00:17:26,933 ERROR [org.ovirt.engine.core.utils.servlet.ServletUtils] (http--0.0.0.0-8080-1) Can't read file "/home/douglas/ovirt-engine/var/lib/ovirt-engine/reports.xml" for request "/ovirt-engine/services/reports-ui", will send a 404 error response. >2014-03-26 00:17:27,001 INFO [org.ovirt.engine.core.bll.LoginUserCommand] (http--0.0.0.0-8080-2) Running command: LoginUserCommand internal: false. >2014-03-26 00:17:32,241 INFO [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (DefaultQuartzScheduler_Worker-16) [4fdae7d9] Running command: SetStoragePoolStatusCommand internal: true. Entities affected : ID: 00000002-0002-0002-0002-0000000003c7 Type: StoragePool >2014-03-26 00:17:32,300 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-16) [4fdae7d9] Correlation ID: 4fdae7d9, Call Stack: null, Custom Event ID: -1, Message: Invalid status on Data Center Default. Setting status to Non Responsive. >2014-03-26 00:17:32,636 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (DefaultQuartzScheduler_Worker-16) [4fdae7d9] hostFromVds::selectedVds - localhost.localdomain, spmStatus SPM, storage pool Default >2014-03-26 00:17:32,647 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (DefaultQuartzScheduler_Worker-16) [4fdae7d9] Initialize Irs proxy from vds: 192.168.100.177 >2014-03-26 00:17:32,669 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-16) [4fdae7d9] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Storage Pool Manager runs on Host localhost.localdomain (Address: 192.168.100.177). >2014-03-26 00:17:32,737 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-6-thread-11) [4fdae7d9] START, SPMGetAllTasksInfoVDSCommand( storagePoolId = 00000002-0002-0002-0002-0000000003c7, ignoreFailoverLimit = false), log id: 37962c8b >2014-03-26 00:17:32,901 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-6-thread-11) [4fdae7d9] -- executeIrsBrokerCommand: Attempting on storage pool 00000002-0002-0002-0002-0000000003c7 >2014-03-26 00:17:32,911 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-6-thread-11) [4fdae7d9] START, HSMGetAllTasksInfoVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e), log id: 2255f243 >2014-03-26 00:17:32,971 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-6-thread-11) [4fdae7d9] FINISH, HSMGetAllTasksInfoVDSCommand, return: [], log id: 2255f243 >2014-03-26 00:17:32,972 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-6-thread-11) [4fdae7d9] FINISH, SPMGetAllTasksInfoVDSCommand, return: [], log id: 37962c8b >2014-03-26 00:17:32,973 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (org.ovirt.thread.pool-6-thread-11) [4fdae7d9] Discovered no tasks on Storage Pool Default >2014-03-26 00:17:44,703 INFO [org.ovirt.engine.core.bll.MaintenanceNumberOfVdssCommand] (http--0.0.0.0-8080-1) [501ad06a] Lock Acquired to object EngineLock [exclusiveLocks= , sharedLocks= key: 00000002-0002-0002-0002-0000000003c7 value: POOL >] >2014-03-26 00:17:44,744 INFO [org.ovirt.engine.core.bll.MaintenanceNumberOfVdssCommand] (org.ovirt.thread.pool-6-thread-22) [501ad06a] Running command: MaintenanceNumberOfVdssCommand internal: false. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:17:44,753 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-22) [501ad06a] START, SetVdsStatusVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, status=PreparingForMaintenance, nonOperationalReason=NONE, stopSpmFailureLogged=true), log id: 35902932 >2014-03-26 00:17:44,755 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-22) [501ad06a] VDS localhost.localdomain is spm and moved from up calling resetIrs. >2014-03-26 00:17:44,757 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ResetIrsVDSCommand] (org.ovirt.thread.pool-6-thread-22) [501ad06a] START, ResetIrsVDSCommand( storagePoolId = 00000002-0002-0002-0002-0000000003c7, ignoreFailoverLimit = false, vdsId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, ignoreStopFailed = false), log id: 47743313 >2014-03-26 00:17:44,761 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand] (org.ovirt.thread.pool-6-thread-22) [501ad06a] START, SpmStopVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, storagePoolId = 00000002-0002-0002-0002-0000000003c7), log id: 20c8c9f9 >2014-03-26 00:17:44,820 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand] (org.ovirt.thread.pool-6-thread-22) [501ad06a] SpmStopVDSCommand::Stopping SPM on vds localhost.localdomain, pool id 00000002-0002-0002-0002-0000000003c7 >2014-03-26 00:17:44,967 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand] (org.ovirt.thread.pool-6-thread-22) [501ad06a] FINISH, SpmStopVDSCommand, log id: 20c8c9f9 >2014-03-26 00:17:44,985 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ResetIrsVDSCommand] (org.ovirt.thread.pool-6-thread-22) [501ad06a] FINISH, ResetIrsVDSCommand, log id: 47743313 >2014-03-26 00:17:44,991 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-22) [501ad06a] FINISH, SetVdsStatusVDSCommand, log id: 35902932 >2014-03-26 00:17:45,013 INFO [org.ovirt.engine.core.bll.MaintenanceNumberOfVdssCommand] (org.ovirt.thread.pool-6-thread-22) [501ad06a] Lock freed to object EngineLock [exclusiveLocks= , sharedLocks= key: 00000002-0002-0002-0002-0000000003c7 value: POOL >] >2014-03-26 00:17:45,070 INFO [org.ovirt.engine.core.bll.MaintenanceVdsCommand] (org.ovirt.thread.pool-6-thread-22) [501ad06a] Running command: MaintenanceVdsCommand internal: true. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:17:45,082 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-22) [501ad06a] Correlation ID: 501ad06a, Job ID: 304a662f-4e43-44f8-b30a-bf89de84be4c, Call Stack: null, Custom Event ID: -1, Message: Host localhost.localdomain was switched to Maintenance mode by admin. >2014-03-26 00:17:45,348 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-38) Updated vds status from Preparing for Maintenance to Maintenance in database, vds = a27b5b25-6c9e-49ee-87e5-8b8295ec326e : localhost.localdomain >2014-03-26 00:17:45,368 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (org.ovirt.thread.pool-6-thread-28) Clearing cache of pool: 00000002-0002-0002-0002-0000000003c7 for problematic entities of VDS: localhost.localdomain. >2014-03-26 00:17:45,373 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStoragePoolVDSCommand] (DefaultQuartzScheduler_Worker-38) START, DisconnectStoragePoolVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, storagePoolId = 00000002-0002-0002-0002-0000000003c7, vds_spm_id = 1), log id: 72749467 >2014-03-26 00:17:50,683 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStoragePoolVDSCommand] (DefaultQuartzScheduler_Worker-38) FINISH, DisconnectStoragePoolVDSCommand, log id: 72749467 >2014-03-26 00:17:50,688 INFO [org.ovirt.engine.core.bll.storage.DisconnectHostFromStoragePoolServersCommand] (DefaultQuartzScheduler_Worker-38) [49487384] Running command: DisconnectHostFromStoragePoolServersCommand internal: true. Entities affected : ID: 00000002-0002-0002-0002-0000000003c7 Type: StoragePool >2014-03-26 00:17:50,728 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (DefaultQuartzScheduler_Worker-38) [49487384] START, DisconnectStorageServerVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, storagePoolId = 00000002-0002-0002-0002-0000000003c7, storageType = NFS, connectionList = [{ id: 43af44f4-8307-4369-a095-e3e1661ba20d, connection: 192.168.0.103:/nfs/iso01, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };{ id: 77a8a653-2cdf-42be-8d78-c55fb59db310, connection: 192.168.0.103:/nfs/iso00, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };{ id: c59eebe4-ec3e-48a0-b30f-d9074caf98fc, connection: 192.168.0.103:/nfs/data00, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 4b86427c >2014-03-26 00:17:50,918 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (DefaultQuartzScheduler_Worker-38) [49487384] FINISH, DisconnectStorageServerVDSCommand, return: {43af44f4-8307-4369-a095-e3e1661ba20d=0, 77a8a653-2cdf-42be-8d78-c55fb59db310=0, c59eebe4-ec3e-48a0-b30f-d9074caf98fc=0}, log id: 4b86427c >2014-03-26 00:17:53,288 INFO [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (DefaultQuartzScheduler_Worker-51) [1e67d9bb] Running command: SetStoragePoolStatusCommand internal: true. Entities affected : ID: 00000002-0002-0002-0002-0000000003c7 Type: StoragePool >2014-03-26 00:17:53,303 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-51) [1e67d9bb] Correlation ID: 1e67d9bb, Call Stack: null, Custom Event ID: -1, Message: Invalid status on Data Center Default. Setting status to Non Responsive. >2014-03-26 00:18:04,373 WARN [org.ovirt.engine.core.compat.backendcompat.PropertyInfo] (http--0.0.0.0-8080-1) Unable to get value of property: vdsName for class org.ovirt.engine.core.common.businessentities.VdsStatic >2014-03-26 00:18:04,373 WARN [org.ovirt.engine.core.compat.backendcompat.PropertyInfo] (http--0.0.0.0-8080-1) Unable to get value of property: vdsName for class org.ovirt.engine.core.common.businessentities.VdsStatic >2014-03-26 00:18:04,374 INFO [org.ovirt.engine.core.bll.UpdateVdsCommand] (http--0.0.0.0-8080-1) [41fcfdc7] Running command: UpdateVdsCommand internal: false. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:18:04,439 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (http--0.0.0.0-8080-1) [41fcfdc7] START, SetVdsStatusVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, status=Installing, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 359d9d82 >2014-03-26 00:18:04,445 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (http--0.0.0.0-8080-1) [41fcfdc7] FINISH, SetVdsStatusVDSCommand, log id: 359d9d82 >2014-03-26 00:18:04,446 WARN [org.ovirt.engine.core.compat.backendcompat.PropertyInfo] (http--0.0.0.0-8080-1) Unable to get value of property: vdsName for class org.ovirt.engine.core.common.businessentities.VdsStatic >2014-03-26 00:18:04,447 WARN [org.ovirt.engine.core.compat.backendcompat.PropertyInfo] (http--0.0.0.0-8080-1) Unable to get value of property: vdsName for class org.ovirt.engine.core.common.businessentities.VdsStatic >2014-03-26 00:18:04,455 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (http--0.0.0.0-8080-1) [41fcfdc7] Lock Acquired to object EngineLock [exclusiveLocks= key: a27b5b25-6c9e-49ee-87e5-8b8295ec326e value: VDS >, sharedLocks= ] >2014-03-26 00:18:04,459 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (org.ovirt.thread.pool-6-thread-31) [41fcfdc7] Running command: InstallVdsCommand internal: true. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:18:04,466 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [41fcfdc7] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host localhost.localdomain. >2014-03-26 00:18:04,479 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (org.ovirt.thread.pool-6-thread-31) [41fcfdc7] Execute upgrade host a27b5b25-6c9e-49ee-87e5-8b8295ec326e, localhost.localdomain >2014-03-26 00:18:04,481 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [41fcfdc7] Correlation ID: 41fcfdc7, Call Stack: null, Custom Event ID: -1, Message: Host localhost.localdomain configuration was updated by admin. >2014-03-26 00:18:04,486 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-31) [41fcfdc7] START, SetVdsStatusVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, status=Installing, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 5d5ac207 >2014-03-26 00:18:04,493 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-31) [41fcfdc7] FINISH, SetVdsStatusVDSCommand, log id: 5d5ac207 >2014-03-26 00:18:04,799 INFO [org.ovirt.engine.core.bll.InstallerMessages] (org.ovirt.thread.pool-6-thread-31) [41fcfdc7] Installation 192.168.100.177: Connected to host 192.168.100.177 with SSH key fingerprint: 4e:d6:8e:e5:0e:2a:54:22:11:6c:68:24:69:3d:6b:54 >2014-03-26 00:18:04,817 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-31) [41fcfdc7] Correlation ID: 41fcfdc7, Call Stack: null, Custom Event ID: -1, Message: Installing Host localhost.localdomain. Connected to host 192.168.100.177 with SSH key fingerprint: 4e:d6:8e:e5:0e:2a:54:22:11:6c:68:24:69:3d:6b:54. >2014-03-26 00:18:04,929 INFO [org.ovirt.engine.core.bll.InstallerMessages] (org.ovirt.thread.pool-6-thread-31) [41fcfdc7] Installation 192.168.100.177: Sending file /usr/share/ovirt-node-iso/ovirt-node-iso-3.0.4-1.0.201401291204.iso to /data/updates/ovirt-node-image.iso >2014-03-26 00:18:04,956 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-31) [41fcfdc7] Correlation ID: 41fcfdc7, Call Stack: null, Custom Event ID: -1, Message: Installing Host localhost.localdomain. Sending file /usr/share/ovirt-node-iso/ovirt-node-iso-3.0.4-1.0.201401291204.iso to /data/updates/ovirt-node-image.iso. >2014-03-26 00:18:04,958 INFO [org.ovirt.engine.core.utils.ssh.SSHDialog] (org.ovirt.thread.pool-6-thread-31) SSH execute root@192.168.100.177 'mkdir -p '/data/updates'' >2014-03-26 00:23:05,082 ERROR [org.ovirt.engine.core.bll.OVirtNodeUpgrade] (org.ovirt.thread.pool-6-thread-31) [41fcfdc7] Timeout during node 192.168.100.177 upgrade: javax.naming.TimeLimitExceededException: SSH session timeout host 'root@192.168.100.177' > at org.ovirt.engine.core.utils.ssh.SSHClient.executeCommand(SSHClient.java:499) [utils.jar:] > at org.ovirt.engine.core.utils.ssh.SSHClient.sendFile(SSHClient.java:623) [utils.jar:] > at org.ovirt.engine.core.utils.ssh.SSHDialog.sendFile(SSHDialog.java:378) [utils.jar:] > at org.ovirt.engine.core.bll.OVirtNodeUpgrade.execute(OVirtNodeUpgrade.java:200) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsCommand.upgradeNode(InstallVdsCommand.java:271) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsCommand.executeCommand(InstallVdsCommand.java:138) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1123) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1208) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1884) [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:1228) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:351) [bll.jar:] > at org.ovirt.engine.core.bll.MultipleActionsRunner.executeValidatedCommand(MultipleActionsRunner.java:189) [bll.jar:] > at org.ovirt.engine.core.bll.MultipleActionsRunner.runCommands(MultipleActionsRunner.java:156) [bll.jar:] > at org.ovirt.engine.core.bll.MultipleActionsRunner$2.run(MultipleActionsRunner.java:165) [bll.jar:] > at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:97) [utils.jar:] > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_51] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_51] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_51] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_51] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_51] > >2014-03-26 00:23:05,118 ERROR [org.ovirt.engine.core.bll.InstallerMessages] (org.ovirt.thread.pool-6-thread-31) [41fcfdc7] Installation 192.168.100.177: Processing stopped due to timeout >2014-03-26 00:23:05,131 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-31) [41fcfdc7] Correlation ID: 41fcfdc7, Call Stack: null, Custom Event ID: -1, Message: Failed to install Host localhost.localdomain. Processing stopped due to timeout. >2014-03-26 00:23:05,144 ERROR [org.ovirt.engine.core.bll.InstallVdsCommand] (org.ovirt.thread.pool-6-thread-31) [41fcfdc7] Host installation failed for host a27b5b25-6c9e-49ee-87e5-8b8295ec326e, localhost.localdomain.: javax.naming.TimeLimitExceededException: SSH session timeout host 'root@192.168.100.177' > at org.ovirt.engine.core.utils.ssh.SSHClient.executeCommand(SSHClient.java:499) [utils.jar:] > at org.ovirt.engine.core.utils.ssh.SSHClient.sendFile(SSHClient.java:623) [utils.jar:] > at org.ovirt.engine.core.utils.ssh.SSHDialog.sendFile(SSHDialog.java:378) [utils.jar:] > at org.ovirt.engine.core.bll.OVirtNodeUpgrade.execute(OVirtNodeUpgrade.java:200) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsCommand.upgradeNode(InstallVdsCommand.java:271) [bll.jar:] > at org.ovirt.engine.core.bll.InstallVdsCommand.executeCommand(InstallVdsCommand.java:138) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1123) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1208) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1884) [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:1228) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:351) [bll.jar:] > at org.ovirt.engine.core.bll.MultipleActionsRunner.executeValidatedCommand(MultipleActionsRunner.java:189) [bll.jar:] > at org.ovirt.engine.core.bll.MultipleActionsRunner.runCommands(MultipleActionsRunner.java:156) [bll.jar:] > at org.ovirt.engine.core.bll.MultipleActionsRunner$2.run(MultipleActionsRunner.java:165) [bll.jar:] > at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:97) [utils.jar:] > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_51] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_51] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_51] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_51] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_51] > >2014-03-26 00:23:05,151 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-31) [41fcfdc7] START, SetVdsStatusVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, status=InstallFailed, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 58bde7ca >2014-03-26 00:23:05,166 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-31) [41fcfdc7] FINISH, SetVdsStatusVDSCommand, log id: 58bde7ca >2014-03-26 00:23:05,179 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-31) [41fcfdc7] Correlation ID: 41fcfdc7, Call Stack: null, Custom Event ID: -1, Message: Host localhost.localdomain installation failed. SSH session timeout host 'root@192.168.100.177'. >2014-03-26 00:23:05,181 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (org.ovirt.thread.pool-6-thread-31) [41fcfdc7] Lock freed to object EngineLock [exclusiveLocks= key: a27b5b25-6c9e-49ee-87e5-8b8295ec326e value: VDS >, sharedLocks= ] >2014-03-26 00:23:59,915 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (http--0.0.0.0-8080-1) [71843e90] Lock Acquired to object EngineLock [exclusiveLocks= key: a27b5b25-6c9e-49ee-87e5-8b8295ec326e value: VDS >, sharedLocks= ] >2014-03-26 00:23:59,962 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-6-thread-32) [71843e90] Running command: ActivateVdsCommand internal: false. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:23:59,965 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-6-thread-32) [71843e90] Before acquiring lock in order to prevent monitoring for host localhost.localdomain from data-center Default >2014-03-26 00:23:59,968 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-6-thread-32) [71843e90] Lock acquired, from now a monitoring of host will be skipped for host localhost.localdomain from data-center Default >2014-03-26 00:23:59,979 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-32) [71843e90] START, SetVdsStatusVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, status=Unassigned, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 6f29609 >2014-03-26 00:23:59,994 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-32) [71843e90] FINISH, SetVdsStatusVDSCommand, log id: 6f29609 >2014-03-26 00:24:00,010 INFO [org.ovirt.engine.core.vdsbroker.ActivateVdsVDSCommand] (org.ovirt.thread.pool-6-thread-32) [71843e90] START, ActivateVdsVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e), log id: 1ed7b45c >2014-03-26 00:24:02,162 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (org.ovirt.thread.pool-6-thread-32) [71843e90] Command GetCapabilitiesVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]) execution failed. Exception: VDSNetworkException: java.net.SocketTimeoutException: connect timed out >2014-03-26 00:24:02,187 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (org.ovirt.thread.pool-6-thread-32) [58ca2607] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:24:02,194 WARN [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (org.ovirt.thread.pool-6-thread-32) [438aad9d] CanDoAction of action HandleVdsVersion failed. Reasons:VDS_CANNOT_CHECK_VERSION_HOST_NON_RESPONSIVE >2014-03-26 00:24:02,197 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (org.ovirt.thread.pool-6-thread-32) [438aad9d] Failed to activate VDS = a27b5b25-6c9e-49ee-87e5-8b8295ec326e with error: java.net.SocketTimeoutException: connect timed out. >2014-03-26 00:24:02,198 INFO [org.ovirt.engine.core.vdsbroker.ActivateVdsVDSCommand] (org.ovirt.thread.pool-6-thread-32) [438aad9d] FINISH, ActivateVdsVDSCommand, return: Host[localhost.localdomain], log id: 1ed7b45c >2014-03-26 00:24:02,226 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-6-thread-32) [438aad9d] Activate finished. Lock released. Monitoring can run now for host localhost.localdomain from data-center Default >2014-03-26 00:24:02,243 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-32) [438aad9d] Correlation ID: 71843e90, Job ID: 86418b28-3c5a-4291-84a8-dce91711d781, Call Stack: null, Custom Event ID: -1, Message: Host localhost.localdomain was activated by admin. >2014-03-26 00:24:02,251 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-6-thread-32) [438aad9d] Lock freed to object EngineLock [exclusiveLocks= key: a27b5b25-6c9e-49ee-87e5-8b8295ec326e value: VDS >, sharedLocks= ] >2014-03-26 00:24:05,405 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-90) Command GetCapabilitiesVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]) execution failed. Exception: VDSNetworkException: java.net.SocketTimeoutException: connect timed out >2014-03-26 00:24:05,410 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-90) Failed to refresh VDS , vds = a27b5b25-6c9e-49ee-87e5-8b8295ec326e : localhost.localdomain, VDS Network Error, continuing. >java.net.SocketTimeoutException: connect timed out >2014-03-26 00:24:08,440 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-97) Command GetCapabilitiesVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-03-26 00:24:08,441 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-97) Server failed to respond, vds_id = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds_name = localhost.localdomain, vm_count = 0, spm_status = None, non-responsive_timeout (seconds) = 60, error = java.net.ConnectException: Connection refused >2014-03-26 00:24:08,451 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-97) Correlation ID: null, Call Stack: org.ovirt.engine.core.vdsbroker.vdsbroker.VDSNetworkException: java.net.ConnectException: Connection refused > at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.executeVDSCommand(VdsBrokerCommand.java:102) > at org.ovirt.engine.core.vdsbroker.VDSCommandBase.executeCommand(VDSCommandBase.java:56) > at org.ovirt.engine.core.dal.VdcCommandBase.execute(VdcCommandBase.java:31) > at org.ovirt.engine.core.vdsbroker.VdsManager.refreshCapabilities(VdsManager.java:537) > at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.refreshVdsRunTimeInfo(VdsUpdateRunTimeInfo.java:491) > at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.refresh(VdsUpdateRunTimeInfo.java:338) > at org.ovirt.engine.core.vdsbroker.VdsManager.onTimer(VdsManager.java:236) > at sun.reflect.GeneratedMethodAccessor46.invoke(Unknown Source) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:606) > at org.ovirt.engine.core.utils.timer.JobWrapper.execute(JobWrapper.java:60) > at org.quartz.core.JobRunShell.run(JobRunShell.java:213) > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:557) >Caused by: java.net.ConnectException: Connection refused > at java.net.PlainSocketImpl.socketConnect(Native Method) > at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339) > at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200) > at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182) > at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) > at java.net.Socket.connect(Socket.java:579) > at sun.security.ssl.SSLSocketImpl.connect(SSLSocketImpl.java:618) > at org.ovirt.engine.core.utils.ssl.AuthSSLProtocolSocketFactory.createSocket(AuthSSLProtocolSocketFactory.java:288) > at org.apache.commons.httpclient.HttpConnection.open(HttpConnection.java:707) > at org.apache.commons.httpclient.MultiThreadedHttpConnectionManager$HttpConnectionAdapter.open(MultiThreadedHttpConnectionManager.java:1361) > at org.apache.commons.httpclient.HttpMethodDirector.executeWithRetry(HttpMethodDirector.java:387) > at org.apache.commons.httpclient.HttpMethodDirector.executeMethod(HttpMethodDirector.java:171) > at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:397) > at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:323) > at org.apache.xmlrpc.client.XmlRpcCommonsTransport.writeRequest(XmlRpcCommonsTransport.java:227) > at org.apache.xmlrpc.client.XmlRpcStreamTransport.sendRequest(XmlRpcStreamTransport.java:151) > at org.apache.xmlrpc.client.XmlRpcHttpTransport.sendRequest(XmlRpcHttpTransport.java:143) > at org.apache.xmlrpc.client.XmlRpcClientWorker.execute(XmlRpcClientWorker.java:56) > at org.apache.xmlrpc.client.XmlRpcClient.execute(XmlRpcClient.java:167) > at org.apache.xmlrpc.client.XmlRpcClient.execute(XmlRpcClient.java:137) > at org.apache.xmlrpc.client.XmlRpcClient.execute(XmlRpcClient.java:126) > at org.apache.xmlrpc.client.util.ClientFactory$1.invoke(ClientFactory.java:140) > at com.sun.proxy.$Proxy54.getVdsCapabilities(Unknown Source) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:606) > at org.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcUtils$AsyncProxy$InternalCallable.call(XmlRpcUtils.java:225) > at java.util.concurrent.FutureTask.run(FutureTask.java:262) > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) > at java.util.concurrent.FutureTask.run(FutureTask.java:262) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) > at java.lang.Thread.run(Thread.java:744) >, Custom Event ID: -1, Message: Host localhost.localdomain is non responsive. >2014-03-26 00:24:08,470 INFO [org.ovirt.engine.core.bll.VdsEventListener] (org.ovirt.thread.pool-6-thread-36) ResourceManager::vdsNotResponding entered for Host a27b5b25-6c9e-49ee-87e5-8b8295ec326e, 192.168.100.177 >2014-03-26 00:24:08,523 INFO [org.ovirt.engine.core.bll.SshSoftFencingCommand] (org.ovirt.thread.pool-6-thread-36) [2e7bad51] Running command: SshSoftFencingCommand internal: true. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:24:08,526 INFO [org.ovirt.engine.core.bll.SshSoftFencingCommand] (org.ovirt.thread.pool-6-thread-36) [2e7bad51] Opening SSH Soft Fencing session on host 192.168.100.177 >2014-03-26 00:24:08,549 ERROR [org.ovirt.engine.core.bll.SshSoftFencingCommand] (org.ovirt.thread.pool-6-thread-36) [2e7bad51] SSH Soft Fencing command failed on host 192.168.100.177: Failed to get the session. >Stdout: >Stderr: >Stacktrace: org.apache.sshd.common.RuntimeSshException: Failed to get the session.: org.apache.sshd.common.RuntimeSshException: Failed to get the session. > at org.apache.sshd.client.future.DefaultConnectFuture.getSession(DefaultConnectFuture.java:43) > at org.ovirt.engine.core.utils.ssh.SSHClient.connect(SSHClient.java:294) [utils.jar:] > at org.ovirt.engine.core.bll.utils.EngineSSHClient.connect(EngineSSHClient.java:59) [bll.jar:] > at org.ovirt.engine.core.bll.SshSoftFencingCommand.executeSshSoftFencingCommand(SshSoftFencingCommand.java:77) [bll.jar:] > at org.ovirt.engine.core.bll.SshSoftFencingCommand.executeCommand(SshSoftFencingCommand.java:44) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1123) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1208) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1884) [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:1228) [bll.jar:] > at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:351) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runAction(Backend.java:413) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:392) [bll.jar:] > at org.ovirt.engine.core.bll.Backend.runInternalAction(Backend.java:634) [bll.jar:] > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_51] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_51] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_51] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_51] > 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.jar:1.1.1.Final] > at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation.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.jar:1.1.1.Final] > at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation.jar:1.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.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.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.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.jar:1.1.1.Final] > at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final] > at org.jboss.as.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.jar:1.1.1.Final] > at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final] > at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final] > at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.jar:1.1.1.Final] > at org.jboss.as.ee.component.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.jar:1.1.1.Final] > at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.jar:1.1.1.Final] > at org.jboss.as.ee.component.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$$$view9.runInternalAction(Unknown Source) [bll.jar:] > at org.ovirt.engine.core.bll.VdsEventListener$1.run(VdsEventListener.java:144) [bll.jar:] > at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:97) [utils.jar:] > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_51] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_51] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_51] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_51] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_51] >Caused by: java.net.ConnectException: Connection refused > at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method) [rt.jar:1.7.0_51] > at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:739) [rt.jar:1.7.0_51] > at org.apache.mina.transport.socket.nio.NioSocketConnector.finishConnect(NioSocketConnector.java:223) [mina-core.jar:] > at org.apache.mina.transport.socket.nio.NioSocketConnector.finishConnect(NioSocketConnector.java:45) [mina-core.jar:] > at org.apache.mina.core.polling.AbstractPollingIoConnector.processConnections(AbstractPollingIoConnector.java:427) [mina-core.jar:] > at org.apache.mina.core.polling.AbstractPollingIoConnector.access$500(AbstractPollingIoConnector.java:62) [mina-core.jar:] > at org.apache.mina.core.polling.AbstractPollingIoConnector$Connector.run(AbstractPollingIoConnector.java:476) [mina-core.jar:] > at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64) [mina-core.jar:] > ... 3 more > >2014-03-26 00:24:08,625 INFO [org.ovirt.engine.core.bll.VdsNotRespondingTreatmentCommand] (org.ovirt.thread.pool-6-thread-36) [34ca132e] Lock Acquired to object EngineLock [exclusiveLocks= key: a27b5b25-6c9e-49ee-87e5-8b8295ec326e value: VDS_FENCE >, sharedLocks= ] >2014-03-26 00:24:08,659 ERROR [org.ovirt.engine.core.bll.VdsNotRespondingTreatmentCommand] (org.ovirt.thread.pool-6-thread-36) [34ca132e] Failed to run Fence script on vds:localhost.localdomain, VMs moved to UnKnown instead. >2014-03-26 00:24:08,665 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-36) [34ca132e] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Host localhost.localdomain became non responsive. It has no power management configured. Please check the host status, manually reboot it, and click "Confirm Host Has Been Rebooted" >2014-03-26 00:24:08,666 WARN [org.ovirt.engine.core.bll.VdsNotRespondingTreatmentCommand] (org.ovirt.thread.pool-6-thread-36) [34ca132e] CanDoAction of action VdsNotRespondingTreatment failed. Reasons:VAR__ACTION__RESTART,VDS_FENCE_DISABLED >2014-03-26 00:24:08,667 INFO [org.ovirt.engine.core.bll.VdsNotRespondingTreatmentCommand] (org.ovirt.thread.pool-6-thread-36) [34ca132e] Lock freed to object EngineLock [exclusiveLocks= key: a27b5b25-6c9e-49ee-87e5-8b8295ec326e value: VDS_FENCE >, sharedLocks= ] >2014-03-26 00:24:11,508 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-1) Command GetCapabilitiesVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-03-26 00:24:14,547 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-6) [1dc431a] Command GetCapabilitiesVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-03-26 00:24:17,566 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-12) Command GetCapabilitiesVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-03-26 00:24:20,601 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-17) Command GetCapabilitiesVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-03-26 00:24:25,625 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-27) Command GetCapabilitiesVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]) execution failed. Exception: VDSNetworkException: java.net.SocketTimeoutException: connect timed out >2014-03-26 00:24:28,647 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-32) Command GetCapabilitiesVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-03-26 00:24:31,667 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-36) Command GetCapabilitiesVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-03-26 00:24:34,685 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-44) Command GetCapabilitiesVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-03-26 00:24:37,701 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-47) Command GetCapabilitiesVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-03-26 00:24:40,722 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-51) [1e67d9bb] Command GetCapabilitiesVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-03-26 00:24:43,744 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler_Worker-58) Command GetCapabilitiesVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]) execution failed. Exception: VDSNetworkException: java.net.ConnectException: Connection refused >2014-03-26 00:24:46,724 INFO [org.ovirt.engine.core.bll.MaintenanceNumberOfVdssCommand] (org.ovirt.thread.pool-6-thread-48) [12c546bd] Running command: MaintenanceNumberOfVdssCommand internal: false. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:24:46,800 INFO [org.ovirt.engine.core.bll.MaintenanceVdsCommand] (org.ovirt.thread.pool-6-thread-48) [12c546bd] Running command: MaintenanceVdsCommand internal: true. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:24:46,805 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-48) [12c546bd] START, SetVdsStatusVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, status=Maintenance, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 3b77af50 >2014-03-26 00:24:47,195 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-62) START, GetHardwareInfoVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]), log id: 54664004 >2014-03-26 00:24:47,332 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-62) FINISH, GetHardwareInfoVDSCommand, log id: 54664004 >2014-03-26 00:24:47,380 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-62) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: State was set to Up for host localhost.localdomain. >2014-03-26 00:24:47,428 INFO [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (DefaultQuartzScheduler_Worker-62) [d0f92c0] Running command: InitVdsOnUpCommand internal: true. Entities affected : ID: 00000002-0002-0002-0002-0000000003c7 Type: StoragePool >2014-03-26 00:24:47,440 INFO [org.ovirt.engine.core.bll.storage.ConnectHostToStoragePoolServersCommand] (DefaultQuartzScheduler_Worker-62) [7b680de2] Running command: ConnectHostToStoragePoolServersCommand internal: true. Entities affected : ID: 00000002-0002-0002-0002-0000000003c7 Type: StoragePool >2014-03-26 00:24:47,461 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (DefaultQuartzScheduler_Worker-62) [7b680de2] START, ConnectStorageServerVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, storagePoolId = 00000002-0002-0002-0002-0000000003c7, storageType = NFS, connectionList = [{ id: 43af44f4-8307-4369-a095-e3e1661ba20d, connection: 192.168.0.103:/nfs/iso01, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };{ id: 77a8a653-2cdf-42be-8d78-c55fb59db310, connection: 192.168.0.103:/nfs/iso00, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };{ id: c59eebe4-ec3e-48a0-b30f-d9074caf98fc, connection: 192.168.0.103:/nfs/data00, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 46849d43 >2014-03-26 00:24:48,791 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (DefaultQuartzScheduler_Worker-62) [7b680de2] FINISH, ConnectStorageServerVDSCommand, return: {43af44f4-8307-4369-a095-e3e1661ba20d=0, 77a8a653-2cdf-42be-8d78-c55fb59db310=0, c59eebe4-ec3e-48a0-b30f-d9074caf98fc=0}, log id: 46849d43 >2014-03-26 00:24:48,797 INFO [org.ovirt.engine.core.bll.storage.ConnectHostToStoragePoolServersCommand] (DefaultQuartzScheduler_Worker-62) [7b680de2] Host localhost.localdomain storage connection was succeeded >2014-03-26 00:24:48,812 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-6-thread-4) START, ConnectStoragePoolVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, storagePoolId = 00000002-0002-0002-0002-0000000003c7, vds_spm_id = 1, masterDomainId = 8fc74a15-abf1-44a8-b89f-971ed28d3029, masterVersion = 1), log id: 377de7e0 >2014-03-26 00:24:50,800 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-6-thread-4) FINISH, ConnectStoragePoolVDSCommand, log id: 377de7e0 >2014-03-26 00:24:50,873 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (DefaultQuartzScheduler_Worker-62) [7b680de2] START, SetMOMPolicyParametersVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e), log id: 1e63ab5b >2014-03-26 00:24:51,005 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (DefaultQuartzScheduler_Worker-62) [7b680de2] FINISH, SetMOMPolicyParametersVDSCommand, log id: 1e63ab5b >2014-03-26 00:24:51,019 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-62) [7b680de2] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host localhost.localdomain. >2014-03-26 00:24:51,041 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (DefaultQuartzScheduler_Worker-62) [46a4678d] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:24:51,052 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (DefaultQuartzScheduler_Worker-62) [d4e9bd5] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:24:51,059 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-48) [12c546bd] FINISH, SetVdsStatusVDSCommand, log id: 3b77af50 >2014-03-26 00:24:51,063 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-48) [12c546bd] Correlation ID: 12c546bd, Job ID: 96c8b864-4bbb-493c-9d78-a4855cd704ab, Call Stack: null, Custom Event ID: -1, Message: Host localhost.localdomain was switched to Maintenance mode by admin. >2014-03-26 00:24:53,794 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (http--0.0.0.0-8080-1) [8e75e4b] Lock Acquired to object EngineLock [exclusiveLocks= key: a27b5b25-6c9e-49ee-87e5-8b8295ec326e value: VDS >, sharedLocks= ] >2014-03-26 00:24:53,831 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-6-thread-12) [8e75e4b] Running command: ActivateVdsCommand internal: false. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:24:53,832 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-6-thread-12) [8e75e4b] Before acquiring lock in order to prevent monitoring for host localhost.localdomain from data-center Default >2014-03-26 00:24:53,833 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-6-thread-12) [8e75e4b] Lock acquired, from now a monitoring of host will be skipped for host localhost.localdomain from data-center Default >2014-03-26 00:24:53,849 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-12) [8e75e4b] START, SetVdsStatusVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, status=Unassigned, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: fc0cd97 >2014-03-26 00:24:53,855 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-12) [8e75e4b] FINISH, SetVdsStatusVDSCommand, log id: fc0cd97 >2014-03-26 00:24:53,858 INFO [org.ovirt.engine.core.vdsbroker.ActivateVdsVDSCommand] (org.ovirt.thread.pool-6-thread-12) [8e75e4b] START, ActivateVdsVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e), log id: 455b9690 >2014-03-26 00:24:54,183 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (org.ovirt.thread.pool-6-thread-12) [8e75e4b] START, GetHardwareInfoVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]), log id: 1c8a449f >2014-03-26 00:24:54,273 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (org.ovirt.thread.pool-6-thread-12) [8e75e4b] FINISH, GetHardwareInfoVDSCommand, log id: 1c8a449f >2014-03-26 00:24:54,297 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (org.ovirt.thread.pool-6-thread-12) [1997968] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:24:54,301 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (org.ovirt.thread.pool-6-thread-12) [6a91775] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:24:54,303 INFO [org.ovirt.engine.core.vdsbroker.ActivateVdsVDSCommand] (org.ovirt.thread.pool-6-thread-12) [6a91775] FINISH, ActivateVdsVDSCommand, return: Host[localhost.localdomain], log id: 455b9690 >2014-03-26 00:24:54,323 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-6-thread-12) [6a91775] Activate finished. Lock released. Monitoring can run now for host localhost.localdomain from data-center Default >2014-03-26 00:24:54,328 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-12) [6a91775] Correlation ID: 8e75e4b, Job ID: 199d4bd8-dfc0-4370-bd08-8d6da21bc3bc, Call Stack: null, Custom Event ID: -1, Message: Host localhost.localdomain was activated by admin. >2014-03-26 00:24:54,332 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (org.ovirt.thread.pool-6-thread-12) [6a91775] Lock freed to object EngineLock [exclusiveLocks= key: a27b5b25-6c9e-49ee-87e5-8b8295ec326e value: VDS >, sharedLocks= ] >2014-03-26 00:24:57,189 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-79) START, GetHardwareInfoVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, vds=Host[localhost.localdomain]), log id: 5ac61daa >2014-03-26 00:24:57,272 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-79) FINISH, GetHardwareInfoVDSCommand, log id: 5ac61daa >2014-03-26 00:24:57,293 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-79) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: State was set to Up for host localhost.localdomain. >2014-03-26 00:24:57,373 INFO [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (DefaultQuartzScheduler_Worker-79) [357a2de1] Running command: InitVdsOnUpCommand internal: true. Entities affected : ID: 00000002-0002-0002-0002-0000000003c7 Type: StoragePool >2014-03-26 00:24:57,381 INFO [org.ovirt.engine.core.bll.storage.ConnectHostToStoragePoolServersCommand] (DefaultQuartzScheduler_Worker-79) [2b976dab] Running command: ConnectHostToStoragePoolServersCommand internal: true. Entities affected : ID: 00000002-0002-0002-0002-0000000003c7 Type: StoragePool >2014-03-26 00:24:57,389 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (DefaultQuartzScheduler_Worker-79) [2b976dab] START, ConnectStorageServerVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, storagePoolId = 00000002-0002-0002-0002-0000000003c7, storageType = NFS, connectionList = [{ id: 43af44f4-8307-4369-a095-e3e1661ba20d, connection: 192.168.0.103:/nfs/iso01, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };{ id: 77a8a653-2cdf-42be-8d78-c55fb59db310, connection: 192.168.0.103:/nfs/iso00, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };{ id: c59eebe4-ec3e-48a0-b30f-d9074caf98fc, connection: 192.168.0.103:/nfs/data00, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 1be14805 >2014-03-26 00:24:57,534 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (DefaultQuartzScheduler_Worker-79) [2b976dab] FINISH, ConnectStorageServerVDSCommand, return: {43af44f4-8307-4369-a095-e3e1661ba20d=0, 77a8a653-2cdf-42be-8d78-c55fb59db310=0, c59eebe4-ec3e-48a0-b30f-d9074caf98fc=0}, log id: 1be14805 >2014-03-26 00:24:57,535 INFO [org.ovirt.engine.core.bll.storage.ConnectHostToStoragePoolServersCommand] (DefaultQuartzScheduler_Worker-79) [2b976dab] Host localhost.localdomain storage connection was succeeded >2014-03-26 00:24:57,540 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-6-thread-18) START, ConnectStoragePoolVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, storagePoolId = 00000002-0002-0002-0002-0000000003c7, vds_spm_id = 1, masterDomainId = 8fc74a15-abf1-44a8-b89f-971ed28d3029, masterVersion = 1), log id: 6388f04e >2014-03-26 00:24:58,093 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (org.ovirt.thread.pool-6-thread-18) FINISH, ConnectStoragePoolVDSCommand, log id: 6388f04e >2014-03-26 00:24:58,142 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (DefaultQuartzScheduler_Worker-79) [2b976dab] START, SetMOMPolicyParametersVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e), log id: 7cb704f6 >2014-03-26 00:24:58,287 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (DefaultQuartzScheduler_Worker-79) [2b976dab] FINISH, SetMOMPolicyParametersVDSCommand, log id: 7cb704f6 >2014-03-26 00:24:58,292 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-79) [2b976dab] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host localhost.localdomain. >2014-03-26 00:24:58,302 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (DefaultQuartzScheduler_Worker-79) [646db148] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:24:58,306 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (DefaultQuartzScheduler_Worker-79) [2d0fe070] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:25:01,954 INFO [org.ovirt.engine.core.bll.MaintenanceNumberOfVdssCommand] (org.ovirt.thread.pool-6-thread-28) [5c072f5b] Running command: MaintenanceNumberOfVdssCommand internal: false. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:25:01,956 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-28) [5c072f5b] START, SetVdsStatusVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, status=PreparingForMaintenance, nonOperationalReason=NONE, stopSpmFailureLogged=true), log id: 1f799067 >2014-03-26 00:25:01,964 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-28) [5c072f5b] FINISH, SetVdsStatusVDSCommand, log id: 1f799067 >2014-03-26 00:25:02,029 INFO [org.ovirt.engine.core.bll.MaintenanceVdsCommand] (org.ovirt.thread.pool-6-thread-28) [5c072f5b] Running command: MaintenanceVdsCommand internal: true. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:25:02,053 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-28) [5c072f5b] Correlation ID: 5c072f5b, Job ID: c6feb4c5-29e6-4502-83bf-c1ded5ff76b6, Call Stack: null, Custom Event ID: -1, Message: Host localhost.localdomain was switched to Maintenance mode by admin. >2014-03-26 00:25:04,571 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-90) Updated vds status from Preparing for Maintenance to Maintenance in database, vds = a27b5b25-6c9e-49ee-87e5-8b8295ec326e : localhost.localdomain >2014-03-26 00:25:04,591 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (org.ovirt.thread.pool-6-thread-31) Clearing cache of pool: 00000002-0002-0002-0002-0000000003c7 for problematic entities of VDS: localhost.localdomain. >2014-03-26 00:25:04,593 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStoragePoolVDSCommand] (DefaultQuartzScheduler_Worker-90) START, DisconnectStoragePoolVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, storagePoolId = 00000002-0002-0002-0002-0000000003c7, vds_spm_id = 1), log id: 49814e75 >2014-03-26 00:25:08,693 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStoragePoolVDSCommand] (DefaultQuartzScheduler_Worker-90) FINISH, DisconnectStoragePoolVDSCommand, log id: 49814e75 >2014-03-26 00:25:08,695 INFO [org.ovirt.engine.core.bll.storage.DisconnectHostFromStoragePoolServersCommand] (DefaultQuartzScheduler_Worker-90) [e58314f] Running command: DisconnectHostFromStoragePoolServersCommand internal: true. Entities affected : ID: 00000002-0002-0002-0002-0000000003c7 Type: StoragePool >2014-03-26 00:25:08,719 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (DefaultQuartzScheduler_Worker-90) [e58314f] START, DisconnectStorageServerVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, storagePoolId = 00000002-0002-0002-0002-0000000003c7, storageType = NFS, connectionList = [{ id: 43af44f4-8307-4369-a095-e3e1661ba20d, connection: 192.168.0.103:/nfs/iso01, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };{ id: 77a8a653-2cdf-42be-8d78-c55fb59db310, connection: 192.168.0.103:/nfs/iso00, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };{ id: c59eebe4-ec3e-48a0-b30f-d9074caf98fc, connection: 192.168.0.103:/nfs/data00, iqn: null, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 46370b60 >2014-03-26 00:25:08,931 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (DefaultQuartzScheduler_Worker-90) [e58314f] FINISH, DisconnectStorageServerVDSCommand, return: {43af44f4-8307-4369-a095-e3e1661ba20d=0, 77a8a653-2cdf-42be-8d78-c55fb59db310=0, c59eebe4-ec3e-48a0-b30f-d9074caf98fc=0}, log id: 46370b60 >2014-03-26 00:25:13,213 WARN [org.ovirt.engine.core.compat.backendcompat.PropertyInfo] (http--0.0.0.0-8080-1) Unable to get value of property: vdsName for class org.ovirt.engine.core.common.businessentities.VdsStatic >2014-03-26 00:25:13,214 WARN [org.ovirt.engine.core.compat.backendcompat.PropertyInfo] (http--0.0.0.0-8080-1) Unable to get value of property: vdsName for class org.ovirt.engine.core.common.businessentities.VdsStatic >2014-03-26 00:25:13,215 INFO [org.ovirt.engine.core.bll.UpdateVdsCommand] (http--0.0.0.0-8080-1) [1ff68e41] Running command: UpdateVdsCommand internal: false. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:25:13,226 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (http--0.0.0.0-8080-1) [1ff68e41] START, SetVdsStatusVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, status=Installing, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 41b8f0f8 >2014-03-26 00:25:13,231 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (http--0.0.0.0-8080-1) [1ff68e41] FINISH, SetVdsStatusVDSCommand, log id: 41b8f0f8 >2014-03-26 00:25:13,232 WARN [org.ovirt.engine.core.compat.backendcompat.PropertyInfo] (http--0.0.0.0-8080-1) Unable to get value of property: vdsName for class org.ovirt.engine.core.common.businessentities.VdsStatic >2014-03-26 00:25:13,233 WARN [org.ovirt.engine.core.compat.backendcompat.PropertyInfo] (http--0.0.0.0-8080-1) Unable to get value of property: vdsName for class org.ovirt.engine.core.common.businessentities.VdsStatic >2014-03-26 00:25:13,234 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (http--0.0.0.0-8080-1) [1ff68e41] Lock Acquired to object EngineLock [exclusiveLocks= key: a27b5b25-6c9e-49ee-87e5-8b8295ec326e value: VDS >, sharedLocks= ] >2014-03-26 00:25:13,236 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] Running command: InstallVdsCommand internal: true. Entities affected : ID: a27b5b25-6c9e-49ee-87e5-8b8295ec326e Type: VDS >2014-03-26 00:25:13,237 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] Execute upgrade host a27b5b25-6c9e-49ee-87e5-8b8295ec326e, localhost.localdomain >2014-03-26 00:25:13,239 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] START, SetVdsStatusVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, status=Installing, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 3b5a6741 >2014-03-26 00:25:13,240 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [1ff68e41] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host localhost.localdomain. >2014-03-26 00:25:13,245 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] FINISH, SetVdsStatusVDSCommand, log id: 3b5a6741 >2014-03-26 00:25:13,252 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [1ff68e41] Correlation ID: 1ff68e41, Call Stack: null, Custom Event ID: -1, Message: Host localhost.localdomain configuration was updated by admin. >2014-03-26 00:25:13,412 INFO [org.ovirt.engine.core.bll.InstallerMessages] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] Installation 192.168.100.177: Connected to host 192.168.100.177 with SSH key fingerprint: 4e:d6:8e:e5:0e:2a:54:22:11:6c:68:24:69:3d:6b:54 >2014-03-26 00:25:13,421 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] Correlation ID: 1ff68e41, Call Stack: null, Custom Event ID: -1, Message: Installing Host localhost.localdomain. Connected to host 192.168.100.177 with SSH key fingerprint: 4e:d6:8e:e5:0e:2a:54:22:11:6c:68:24:69:3d:6b:54. >2014-03-26 00:25:13,484 INFO [org.ovirt.engine.core.bll.InstallerMessages] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] Installation 192.168.100.177: Sending file /usr/share/ovirt-node-iso/ovirt-node-iso-3.0.4-1.0.201401291204.iso to /data/updates/ovirt-node-image.iso >2014-03-26 00:25:13,494 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] Correlation ID: 1ff68e41, Call Stack: null, Custom Event ID: -1, Message: Installing Host localhost.localdomain. Sending file /usr/share/ovirt-node-iso/ovirt-node-iso-3.0.4-1.0.201401291204.iso to /data/updates/ovirt-node-image.iso. >2014-03-26 00:25:13,495 INFO [org.ovirt.engine.core.utils.ssh.SSHDialog] (org.ovirt.thread.pool-6-thread-34) SSH execute root@192.168.100.177 'mkdir -p '/data/updates'' >2014-03-26 00:25:34,390 INFO [org.ovirt.engine.core.bll.InstallerMessages] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] Installation 192.168.100.177: Executing /usr/share/vdsm-reg/vdsm-upgrade >2014-03-26 00:25:34,399 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] Correlation ID: 1ff68e41, Call Stack: null, Custom Event ID: -1, Message: Installing Host localhost.localdomain. Executing /usr/share/vdsm-reg/vdsm-upgrade. >2014-03-26 00:25:34,400 INFO [org.ovirt.engine.core.utils.ssh.SSHDialog] (org.ovirt.thread.pool-6-thread-34) SSH execute root@192.168.100.177 '/usr/share/vdsm-reg/vdsm-upgrade' >2014-03-26 00:25:52,675 INFO [org.ovirt.engine.core.bll.OVirtNodeUpgrade] (OVirtNodeUpgrade) update from host 192.168.100.177: <BSTRAP component='RHEL_INSTALL' status='OK' message='vdsm daemon stopped for upgrade process!'/> >2014-03-26 00:25:52,683 INFO [org.ovirt.engine.core.bll.InstallerMessages] (OVirtNodeUpgrade) Installation 192.168.100.177: Step: RHEL_INSTALL; Details: vdsm daemon stopped for upgrade process! >2014-03-26 00:25:52,691 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (OVirtNodeUpgrade) Correlation ID: 1ff68e41, Call Stack: null, Custom Event ID: -1, Message: Installing Host localhost.localdomain. Step: RHEL_INSTALL; Details: vdsm daemon stopped for upgrade process! . >2014-03-26 00:25:52,691 INFO [org.ovirt.engine.core.bll.OVirtNodeUpgrade] (OVirtNodeUpgrade) update from host 192.168.100.177: <BSTRAP component='setMountPoint' status='OK' message='Mount succeeded.'/> >2014-03-26 00:25:52,695 INFO [org.ovirt.engine.core.bll.InstallerMessages] (OVirtNodeUpgrade) Installation 192.168.100.177: Step: setMountPoint; Details: Mount succeeded. >2014-03-26 00:25:52,716 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (OVirtNodeUpgrade) Correlation ID: 1ff68e41, Call Stack: null, Custom Event ID: -1, Message: Installing Host localhost.localdomain. Step: setMountPoint; Details: Mount succeeded. . >2014-03-26 00:25:52,717 INFO [org.ovirt.engine.core.bll.OVirtNodeUpgrade] (OVirtNodeUpgrade) update from host 192.168.100.177: <BSTRAP component='doUpgrade' status='OK' message='Upgrade Succeeded. Rebooting'/> >2014-03-26 00:25:52,720 INFO [org.ovirt.engine.core.bll.InstallerMessages] (OVirtNodeUpgrade) Installation 192.168.100.177: Step: doUpgrade; Details: Upgrade Succeeded. Rebooting >2014-03-26 00:25:52,725 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (OVirtNodeUpgrade) Correlation ID: 1ff68e41, Call Stack: null, Custom Event ID: -1, Message: Installing Host localhost.localdomain. Step: doUpgrade; Details: Upgrade Succeeded. Rebooting . >2014-03-26 00:25:52,726 INFO [org.ovirt.engine.core.bll.OVirtNodeUpgrade] (OVirtNodeUpgrade) update from host 192.168.100.177: <BSTRAP component='umount' status='OK' message='umount Succeeded'/> >2014-03-26 00:25:52,728 INFO [org.ovirt.engine.core.bll.InstallerMessages] (OVirtNodeUpgrade) Installation 192.168.100.177: Step: umount; Details: umount Succeeded >2014-03-26 00:25:52,733 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (OVirtNodeUpgrade) Correlation ID: 1ff68e41, Call Stack: null, Custom Event ID: -1, Message: Installing Host localhost.localdomain. Step: umount; Details: umount Succeeded . >2014-03-26 00:25:52,735 INFO [org.ovirt.engine.core.bll.OVirtNodeUpgrade] (OVirtNodeUpgrade) update from host 192.168.100.177: <BSTRAP component='RHEV_INSTALL' status='OK'/> >2014-03-26 00:25:52,736 INFO [org.ovirt.engine.core.bll.InstallerMessages] (OVirtNodeUpgrade) Installation 192.168.100.177: Step: RHEV_INSTALL >2014-03-26 00:25:52,741 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (OVirtNodeUpgrade) Correlation ID: 1ff68e41, Call Stack: null, Custom Event ID: -1, Message: Installing Host localhost.localdomain. Step: RHEV_INSTALL. >2014-03-26 00:25:52,742 ERROR [org.ovirt.engine.core.bll.OVirtNodeUpgrade] (OVirtNodeUpgrade) Error during upgrade: java.io.IOException: Pipe closed > at java.io.PipedInputStream.read(PipedInputStream.java:308) [rt.jar:1.7.0_51] > at java.io.PipedInputStream.read(PipedInputStream.java:378) [rt.jar:1.7.0_51] > at sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:283) [rt.jar:1.7.0_51] > at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:325) [rt.jar:1.7.0_51] > at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:177) [rt.jar:1.7.0_51] > at java.io.InputStreamReader.read(InputStreamReader.java:184) [rt.jar:1.7.0_51] > at java.io.BufferedReader.fill(BufferedReader.java:154) [rt.jar:1.7.0_51] > at java.io.BufferedReader.readLine(BufferedReader.java:317) [rt.jar:1.7.0_51] > at java.io.BufferedReader.readLine(BufferedReader.java:382) [rt.jar:1.7.0_51] > at org.ovirt.engine.core.bll.OVirtNodeUpgrade._threadMain(OVirtNodeUpgrade.java:56) [bll.jar:] > at org.ovirt.engine.core.bll.OVirtNodeUpgrade.access$000(OVirtNodeUpgrade.java:25) [bll.jar:] > at org.ovirt.engine.core.bll.OVirtNodeUpgrade$1.run(OVirtNodeUpgrade.java:96) [bll.jar:] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_51] > >2014-03-26 00:25:52,751 ERROR [org.ovirt.engine.core.bll.OVirtNodeUpgrade] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] Error during node 192.168.100.177 upgrade: java.io.IOException: Pipe closed > at java.io.PipedInputStream.read(PipedInputStream.java:308) [rt.jar:1.7.0_51] > at java.io.PipedInputStream.read(PipedInputStream.java:378) [rt.jar:1.7.0_51] > at sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:283) [rt.jar:1.7.0_51] > at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:325) [rt.jar:1.7.0_51] > at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:177) [rt.jar:1.7.0_51] > at java.io.InputStreamReader.read(InputStreamReader.java:184) [rt.jar:1.7.0_51] > at java.io.BufferedReader.fill(BufferedReader.java:154) [rt.jar:1.7.0_51] > at java.io.BufferedReader.readLine(BufferedReader.java:317) [rt.jar:1.7.0_51] > at java.io.BufferedReader.readLine(BufferedReader.java:382) [rt.jar:1.7.0_51] > at org.ovirt.engine.core.bll.OVirtNodeUpgrade._threadMain(OVirtNodeUpgrade.java:56) [bll.jar:] > at org.ovirt.engine.core.bll.OVirtNodeUpgrade.access$000(OVirtNodeUpgrade.java:25) [bll.jar:] > at org.ovirt.engine.core.bll.OVirtNodeUpgrade$1.run(OVirtNodeUpgrade.java:96) [bll.jar:] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_51] > >2014-03-26 00:25:52,763 ERROR [org.ovirt.engine.core.bll.OVirtNodeUpgrade] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] Error during node 192.168.100.177 upgrade, prefering first exception: java.io.IOException: Pipe closed > at java.io.PipedInputStream.read(PipedInputStream.java:308) [rt.jar:1.7.0_51] > at java.io.PipedInputStream.read(PipedInputStream.java:378) [rt.jar:1.7.0_51] > at sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:283) [rt.jar:1.7.0_51] > at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:325) [rt.jar:1.7.0_51] > at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:177) [rt.jar:1.7.0_51] > at java.io.InputStreamReader.read(InputStreamReader.java:184) [rt.jar:1.7.0_51] > at java.io.BufferedReader.fill(BufferedReader.java:154) [rt.jar:1.7.0_51] > at java.io.BufferedReader.readLine(BufferedReader.java:317) [rt.jar:1.7.0_51] > at java.io.BufferedReader.readLine(BufferedReader.java:382) [rt.jar:1.7.0_51] > at org.ovirt.engine.core.bll.OVirtNodeUpgrade._threadMain(OVirtNodeUpgrade.java:56) [bll.jar:] > at org.ovirt.engine.core.bll.OVirtNodeUpgrade.access$000(OVirtNodeUpgrade.java:25) [bll.jar:] > at org.ovirt.engine.core.bll.OVirtNodeUpgrade$1.run(OVirtNodeUpgrade.java:96) [bll.jar:] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_51] > >2014-03-26 00:25:52,772 ERROR [org.ovirt.engine.core.bll.InstallVdsCommand] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] Host installation failed for host a27b5b25-6c9e-49ee-87e5-8b8295ec326e, localhost.localdomain.: java.io.IOException: Pipe closed > at java.io.PipedInputStream.read(PipedInputStream.java:308) [rt.jar:1.7.0_51] > at java.io.PipedInputStream.read(PipedInputStream.java:378) [rt.jar:1.7.0_51] > at sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:283) [rt.jar:1.7.0_51] > at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:325) [rt.jar:1.7.0_51] > at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:177) [rt.jar:1.7.0_51] > at java.io.InputStreamReader.read(InputStreamReader.java:184) [rt.jar:1.7.0_51] > at java.io.BufferedReader.fill(BufferedReader.java:154) [rt.jar:1.7.0_51] > at java.io.BufferedReader.readLine(BufferedReader.java:317) [rt.jar:1.7.0_51] > at java.io.BufferedReader.readLine(BufferedReader.java:382) [rt.jar:1.7.0_51] > at org.ovirt.engine.core.bll.OVirtNodeUpgrade._threadMain(OVirtNodeUpgrade.java:56) [bll.jar:] > at org.ovirt.engine.core.bll.OVirtNodeUpgrade.access$000(OVirtNodeUpgrade.java:25) [bll.jar:] > at org.ovirt.engine.core.bll.OVirtNodeUpgrade$1.run(OVirtNodeUpgrade.java:96) [bll.jar:] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_51] > >2014-03-26 00:25:52,779 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] START, SetVdsStatusVDSCommand(HostName = localhost.localdomain, HostId = a27b5b25-6c9e-49ee-87e5-8b8295ec326e, status=InstallFailed, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 108c6539 >2014-03-26 00:25:52,785 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] FINISH, SetVdsStatusVDSCommand, log id: 108c6539 >2014-03-26 00:25:52,790 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] Correlation ID: 1ff68e41, Call Stack: null, Custom Event ID: -1, Message: Host localhost.localdomain installation failed. Pipe closed. >2014-03-26 00:25:52,792 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (org.ovirt.thread.pool-6-thread-34) [1ff68e41] Lock freed to object EngineLock [exclusiveLocks= key: a27b5b25-6c9e-49ee-87e5-8b8295ec326e value: VDS >, sharedLocks= ]
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=878846">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 1080594
: 878846