<div dir="ltr"><div class="gmail_extra"><div class="gmail_quote">On Thu, Oct 22, 2015 at 11:50 AM, Gianluca Cecchi <span dir="ltr">&lt;<a href="mailto:gianluca.cecchi@gmail.com" target="_blank">gianluca.cecchi@gmail.com</a>&gt;</span> wrote:<br><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left-width:1px;border-left-color:rgb(204,204,204);border-left-style:solid;padding-left:1ex"><div dir="ltr"><div class="gmail_extra"><div class="gmail_quote"><span class="">On Wed, Oct 21, 2015 at 6:15 PM, Simone Tiraboschi <span dir="ltr">&lt;<a href="mailto:stirabos@redhat.com" target="_blank">stirabos@redhat.com</a>&gt;</span> wrote:<br><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left-width:1px;border-left-color:rgb(204,204,204);border-left-style:solid;padding-left:1ex"><div dir="ltr"><div class="gmail_extra"><div class="gmail_quote"><div><div><div><br></div></div></div><div>It pools engine REST API checking the host status for 10 minutes till it become &#39;up&#39; or &#39;non_operational&#39;. In your case it reached the 10 minutes timeout.</div><div>Please check engine and host-deploy logs on the engine VM.</div></div></div></div></blockquote><div><br></div></span><div>Ok. </div><div><br></div></div></div></div></blockquote><div><br></div><div>What I see inside ovirt-host-deploy log file </div><div><br></div><div> 2015-10-21 17:20:26 DEBUG otopi.plugins.otopi.packagers.dnfpackager dnfpackager._boot:178 Cannot initialize minidnf</div><div>Traceback (most recent call last):</div><div>  File &quot;/tmp/ovirt-xP0lq4KMou/otopi-plugins/otopi/packagers/dnfpackager.py&quot;, line 165, in _boot</div><div>    constants.PackEnv.DNF_DISABLED_PLUGINS</div><div>  File &quot;/tmp/ovirt-xP0lq4KMou/otopi-plugins/otopi/packagers/dnfpackager.py&quot;, line 75, in _getMiniDNF</div><div>    from otopi import minidnf</div><div>  File &quot;/tmp/ovirt-xP0lq4KMou/pythonlib/otopi/minidnf.py&quot;, line 9, in &lt;module&gt;</div><div>    import dnf</div><div>ImportError: No module named dnf</div><div><br></div><div>...</div><div><br></div><div>2015-10-21 17:36:33 DEBUG otopi.plugins.otopi.dialog.machine dialog.__logString:219 DIALOG:SEND       ### Please input VDSM certificate chain that matches certificate request, top is issuer</div><div>2015-10-21 17:36:33 DEBUG otopi.plugins.otopi.dialog.machine dialog.__logString:219 DIALOG:SEND       ###</div><div>2015-10-21 17:36:33 DEBUG otopi.plugins.otopi.dialog.machine dialog.__logString:219 DIALOG:SEND       ### type &#39;--=451b80dc-996f-432e-9e4f-2b29ef6d1141=--&#39; in own line to mark end, &#39;--=451b80dc-996f-ABORT-9e4f-2b29ef6d1141=--&#39; aborts</div><div>2015-10-21 17:36:33 DEBUG otopi.context context._executeMethod:156 method exception</div><div>Traceback (most recent call last):</div><div>  File &quot;/tmp/ovirt-xP0lq4KMou/pythonlib/otopi/context.py&quot;, line 146, in _executeMethod</div><div>    method[&#39;method&#39;]()</div><div>  File &quot;/tmp/ovirt-xP0lq4KMou/otopi-plugins/ovirt-host-common/vdsm/pki.py&quot;, line 319, in _misc</div><div>    &#39;\n\nPlease input VDSM certificate chain that &#39;</div><div>  File &quot;/tmp/ovirt-xP0lq4KMou/otopi-plugins/otopi/dialog/machine.py&quot;, line 207, in queryMultiString</div><div>    v = self._readline()</div><div>  File &quot;/tmp/ovirt-xP0lq4KMou/pythonlib/otopi/dialog.py&quot;, line 263, in _readline</div><div>    raise IOError(_(&#39;End of file&#39;))</div><div>IOError: End of file</div><div>2015-10-21 17:36:33 ERROR otopi.context context._executeMethod:165 Failed to execute stage &#39;Misc configuration&#39;: End of file</div><div>2015-10-21 17:36:33 DEBUG otopi.transaction transaction.abort:134 aborting &#39;Yum Transaction&#39;</div><div>2015-10-21 17:36:33 INFO otopi.plugins.otopi.packagers.yumpackager <a href="http://yumpackager.info:95">yumpackager.info:95</a> Yum Performing yum transaction rollback</div><div>Loaded plugins: fastestmirror, langpacks</div><div><br></div><div>And in engine.log</div><div><br></div><div><div>2015-10-21 15:19:11,061 INFO  [org.ovirt.engine.core.bll.Backend] (ServerService Thread Pool -- 43) [] </div><div>Started task scheduler org.ovirt.engine.core.utils.timer.SchedulerUtilQuartzImpl@7160a32a</div><div>2015-10-21 15:19:11,321 INFO  [org.ovirt.engine.core.bll.Backend] (ServerService Thread Pool -- 43) [] </div><div>Started task scheduler org.ovirt.engine.core.utils.timer.DBSchedulerUtilQuartzImpl@55760e6a</div><div>2015-10-21 15:19:11,746 INFO  [org.ovirt.engine.core.bll.Backend] (ServerService Thread Pool -- 43) [] </div><div>Start org.ovirt.engine.core.dal.utils.CacheManager@2a4c024b </div><div>2015-10-21 15:19:11,957 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (ServerService Thread Pool </div><div>-- 43) [] Could not find enum value for option: &#39;MigrateDowntime&#39;</div><div>2015-10-21 15:19:11,957 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (ServerService Thread Pool </div><div>-- 43) [] Could not find enum value for option: &#39;MigrateDowntime&#39;</div><div>2015-10-21 15:19:11,957 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (ServerService Thread Pool </div><div>-- 43) [] Could not find enum value for option: &#39;MigrateDowntime&#39;</div><div>2015-10-21 15:19:11,958 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (ServerService Thread Pool </div><div>-- 43) [] Could not find enum value for option: &#39;MigrateDowntime&#39;</div><div>2015-10-21 15:19:11,958 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (ServerService Thread Pool </div><div>-- 43) [] Could not find enum value for option: &#39;MigrateDowntime&#39;</div><div>2015-10-21 15:19:11,958 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (ServerService Thread Pool </div><div>-- 43) [] Could not find enum value for option: &#39;MigrateDowntime&#39;</div><div>2015-10-21 15:19:11,964 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (ServerService Thread Pool -- 43) [] Error parsing option &#39;AutoRecoveryAllowedTypes&#39; value: org.codehaus.jackson.JsonParseException: Unexpected character (&#39;\&#39; (code 92)): was expecting double-quote to start field name</div><div> at [Source: java.io.StringReader@21b12337; line: 1, column: 3]</div><div>2015-10-21 15:19:11,969 INFO  [org.ovirt.engine.core.utils.osinfo.OsInfoPreferencesLoader] (ServerService Thread Pool -- 43) [] Loading file &#39;/etc/ovirt-engine/osinfo.conf.d/00-defaults.properties&#39;</div><div>2015-10-21 15:19:12,322 INFO  [org.ovirt.engine.core.bll.Backend] (ServerService Thread Pool -- 43) [] Running ovirt-engine 3.6.0.1-1.el7.centos</div></div><div><div>2015-10-21 15:19:12,322 INFO  [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (ServerService Thread Pool -- 43) [] Start initializing dictionaries</div><div>2015-10-21 15:19:12,328 INFO  [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (ServerService Thread Pool -- 43) [] Finished initializing dictionaries</div></div><div>...</div><div><div>2015-10-21 15:35:08,852 ERROR [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-61) [] Timer update runtime info failed. Exception:: org.springframework.jdbc.CannotGetJdbcConnectionException: Could not get JDBC Connection; nested exception is java.sql.SQLException: javax.resource.ResourceException: IJ000451: The connection manager is shutdown: java:/ENGINEDataSource</div><div>        at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:80) [spring-jdbc.jar:3.1.1.RELEASE]</div><div>        at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:573) [spring-jdbc.jar:3.1.1.RELEASE]</div><div>        at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:637) [spring-jdbc.jar:3.1.1.RELEASE]</div><div>        at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:666) [spring-jdbc.jar:3.1.1.RELEASE]</div><div>        at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:706) [spring-jdbc.jar:3.1.1.RELEASE]</div><div>        at org.ovirt.engine.core.dal.dbbroker.PostgresDbEngineDialect$PostgresSimpleJdbcCall.executeCallInternal(PostgresDbEngineDialect.java:154) [dal.jar:]</div><div>        at org.ovirt.engine.core.dal.dbbroker.PostgresDbEngineDialect$PostgresSimpleJdbcCall.doExecute(PostgresDbEngineDialect.java:120) [dal.jar:]</div><div>        at org.springframework.jdbc.core.simple.SimpleJdbcCall.execute(SimpleJdbcCall.java:181) [spring-jdbc.jar:3.1.1.RELEASE]</div><div>        at org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeImpl(SimpleJdbcCallsHandler.java:147) [dal.jar:]</div><div>        at org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeReadList(SimpleJdbcCallsHandler.java:109) [dal.jar:]</div><div>        at org.ovirt.engine.core.dao.VdsDaoImpl.get(VdsDaoImpl.java:53) [dal.jar:]</div><div>        at org.ovirt.engine.core.dao.VdsDaoImpl.get(VdsDaoImpl.java:47) [dal.jar:]</div><div>        at org.ovirt.engine.core.vdsbroker.VdsManager.refreshCachedVds(VdsManager.java:278) [vdsbroker.</div></div><div> jar:]</div><div>        at org.ovirt.engine.core.vdsbroker.VdsManager.onTimer(VdsManager.java:208) [vdsbroker.jar:]</div><div>        at sun.reflect.GeneratedMethodAccessor133.invoke(Unknown Source) [:1.7.0_85]</div><div>        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_85]</div><div>        at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_85]</div><div>        at org.ovirt.engine.core.utils.timer.JobWrapper.invokeMethod(JobWrapper.java:81) [scheduler.jar:]</div><div>        at org.ovirt.engine.core.utils.timer.JobWrapper.execute(JobWrapper.java:52) [scheduler.jar:]</div><div>        at org.quartz.core.JobRunShell.run(JobRunShell.java:213) [quartz.jar:]</div><div>        at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:557) [quartz.jar:]</div><div>Caused by: java.sql.SQLException: javax.resource.ResourceException: IJ000451: The connection manager is shutdown: java:/ENGINEDataSource</div><div>        at org.jboss.jca.adapters.jdbc.WrapperDataSource.getConnection(WrapperDataSource.java:154)</div><div>        at sun.reflect.GeneratedMethodAccessor59.invoke(Unknown Source) [:1.7.0_85]</div><div>        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_85]</div><div>        at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_85]</div><div>        at org.jboss.weld.util.reflection.Reflections.invokeAndUnwrap(Reflections.java:414) [weld-core-impl-2.2.6.Final.jar:2014-10-03 10:05]</div><div>        at org.jboss.weld.bean.builtin.CallableMethodHandler.invoke(CallableMethodHandler.java:42) [weld-core-impl-2.2.6.Final.jar:2014-10-03 10:05]</div><div>        at org.jboss.weld.bean.proxy.EnterpriseTargetBeanInstance.invoke(EnterpriseTargetBeanInstance.java:56) [weld-core-impl-2.2.6.Final.jar:2014-10-03 10:05]</div><div>        at org.jboss.weld.bean.proxy.ProxyMethodHandler.invoke(ProxyMethodHandler.java:100) [weld-core-impl-2.2.6.Final.jar:2014-10-03 10:05]</div><div>        at org.jboss.weldx.sql.CommonDataSource$DataSource$Wrapper$1587847696$Proxy$_$$_Weld$Proxy$.getConnection(Unknown Source)</div><div>        at org.springframework.jdbc.datasource.DataSourceUtils.doGetConnection(DataSourceUtils.java:111) [spring-jdbc.jar:3.1.1.RELEASE]</div><div><div>        at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:77) [spring-jdbc.jar:3.1.1.RELEASE]</div><div>        ... 20 more</div><div>Caused by: javax.resource.ResourceException: IJ000451: The connection manager is shutdown: java:/ENGINEDataSource</div><div>        at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.getManagedConnection(AbstractConnectionManager.java:371)</div><div>        at org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl.getManagedConnection(TxConnectionManagerImpl.java:421)</div><div>        at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.allocateConnection(AbstractConnectionManager.java:515)</div><div>        at org.jboss.jca.adapters.jdbc.WrapperDataSource.getConnection(WrapperDataSource.java:146)</div><div>        ... 30 more</div><div><br></div><div>2015-10-21 15:35:11,887 ERROR [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-65) [] Timer update runtime info failed. Exception:: org.springframework.jdbc.CannotGetJdbcConnectionException: Could not get JDBC Connection; nested exception is java.sql.SQLException: javax.resource.ResourceException: IJ000451: The connection manager is shutdown: java:/ENGINEDataSource</div></div><div><br></div></div></div></div>