Re: [ovirt-devel] [ OST Failure Report ] [ oVirt 4.2 (ovirt-hosted-engine-setup) ] [ 08-03-2018 ] [ 004_basic_sanity.run_vms ]

Thursday, 8 March 2018

Does not seem to be hosted engine related.. there is no way we could
cause internal engine error by using the API alone.

Martin

On Thu, Mar 8, 2018 at 12:53 PM, Dafna Ron <dron(a)redhat.com&gt; wrote:
...
 Hi,

 We have a failed test on ovirt-hosted-engine-setup basic suite.
 We failed to run a vm with internal engine error.

 Link and headline of suspected patches:

 Add engine fqdn to inventory and allow dynamic inventory scripts -
 https://gerrit.ovirt.org/#/c/88622/

 Link to Job:

 http://jenkins.ovirt.org/job/ovirt-4.2_change-queue-tester/1064/

 Link to all logs:

 http://jenkins.ovirt.org/job/ovirt-4.2_change-queue-tester/1064/artifact/

 (Relevant) error snippet from the log:

 <error>

 2018-03-07 17:03:38,374-05 INFO
 [org.ovirt.engine.core.bll.RunVmOnceCommand] (default task-21)
 [a514da44-d44f-46b9-8bdf-08ba2d513929] Running command: RunVmOnceCommand
 internal: false. Entities affected :  ID: 634c6a46-d057-4509-be3b-710
 716cbd56d Type: VMAction group RUN_VM with role type USER,  ID:
 634c6a46-d057-4509-be3b-710716cbd56d Type: VMAction group
 EDIT_ADMIN_VM_PROPERTIES with role type ADMIN
 2018-03-07 17:03:38,379-05 DEBUG
 [org.ovirt.engine.core.common.di.interceptor.DebugLoggingInterceptor]
 (default task-21) [a514da44-d44f-46b9-8bdf-08ba2d513929] method:
 getVmManager, params: [634c6a46-d057-4509-be3b-710716cbd56d], timeElap
 sed: 5ms
 2018-03-07 17:03:38,391-05 DEBUG
 [org.ovirt.engine.core.common.di.interceptor.DebugLoggingInterceptor]
 (default task-21) [a514da44-d44f-46b9-8bdf-08ba2d513929] method:
 getAllForClusterWithStatus, params: [14cad400-49a0-44e0-ab15-9da778f08
 082, Up], timeElapsed: 7ms
 2018-03-07 17:03:38,408-05 DEBUG
 [org.ovirt.engine.core.common.di.interceptor.DebugLoggingInterceptor]
 (default task-21) [a514da44-d44f-46b9-8bdf-08ba2d513929] method:
 getVdsManager, params: [8ef2f490-1b76-46e0-b9fe-f3412a36e03b], timeEla
 psed: 0ms
 2018-03-07 17:03:38,419-05 DEBUG
 [org.ovirt.engine.core.dal.dbbroker.CustomSQLErrorCodeSQLExceptionTranslator]
 (default task-21) [a514da44-d44f-46b9-8bdf-08ba2d513929] Translating
 SQLException with SQL state '23505', error code '0', messa
 ge [ERROR: duplicate key value violates unique constraint "name_server_pkey"
   Detail: Key (dns_resolver_configuration_id,
 address)=(8a941b6a-83aa-44de-9800-2a1ea6e8e029, 192.168.200.1) already
 exists.
   Where: SQL statement "INSERT INTO
     name_server(
       address,
       position,
       dns_resolver_configuration_id)
     VALUES (
       v_address,
       v_position,
       v_dns_resolver_configuration_id)"
 PL/pgSQL function insertnameserver(uuid,character varying,smallint) line 3
 at SQL statement]; SQL was [{call insertnameserver(?, ?, ?)}] for task
 [CallableStatementCallback]
 2018-03-07 17:03:38,420-05 ERROR
 [org.ovirt.engine.core.bll.RunVmOnceCommand] (default task-21)
 [a514da44-d44f-46b9-8bdf-08ba2d513929] Command
 'org.ovirt.engine.core.bll.RunVmOnceCommand' failed:
 CallableStatementCallback; SQL [{call inse
 rtnameserver(?, ?, ?)}]; ERROR: duplicate key value violates unique
 constraint "name_server_pkey"
   Detail: Key (dns_resolver_configuration_id,
 address)=(8a941b6a-83aa-44de-9800-2a1ea6e8e029, 192.168.200.1) already
 exists.
   Where: SQL statement "INSERT INTO
     name_server(
       address,
       position,
       dns_resolver_configuration_id)
     VALUES (
       v_address,
       v_position,
       v_dns_resolver_configuration_id)"
 PL/pgSQL function insertnameserver(uuid,character varying,smallint) line 3
 at SQL statement; nested exception is org.postgresql.util.PSQLException:
 ERROR: duplicate key value violates unique constraint "name_server_pkey"
   Detail: Key (dns_resolver_configuration_id,
 address)=(8a941b6a-83aa-44de-9800-2a1ea6e8e029, 192.168.200.1) already
 exists.
   Where: SQL statement "INSERT INTO
     name_server(
       address,
       position,
       dns_resolver_configuration_id)
     VALUES (
       v_address,
       v_position,
       v_dns_resolver_configuration_id)"
 PL/pgSQL function insertnameserver(uuid,character varying,smallint) line 3
 at SQL statement
 2018-03-07 17:03:38,420-05 ERROR
 [org.ovirt.engine.core.bll.RunVmOnceCommand] (default task-21)
 [a514da44-d44f-46b9-8bdf-08ba2d513929] Exception:
 org.springframework.dao.DuplicateKeyException: CallableStatementCallback;
 SQL [{call insertn
 ameserver(?, ?, ?)}]; ERROR: duplicate key value violates unique constraint
 "name_server_pkey"
   Detail: Key (dns_resolver_configuration_id,
 address)=(8a941b6a-83aa-44de-9800-2a1ea6e8e029, 192.168.200.1) already
 exists.
   Where: SQL statement "INSERT INTO
     name_server(
       address,
       position,
       dns_resolver_configuration_id)
     VALUES (
 :
    v_address,
       v_position,
       v_dns_resolver_configuration_id)"
 PL/pgSQL function insertnameserver(uuid,character varying,smallint) line 3
 at SQL statement; nested exception is org.postgresql.util.PSQLException:
 ERROR: duplicate key value violates unique constraint "name_server_pkey"
   Detail: Key (dns_resolver_configuration_id,
 address)=(8a941b6a-83aa-44de-9800-2a1ea6e8e029, 192.168.200.1) already
 exists.
   Where: SQL statement "INSERT INTO
     name_server(
       address,
       position,
       dns_resolver_configuration_id)
     VALUES (
       v_address,
       v_position,
       v_dns_resolver_configuration_id)"
 PL/pgSQL function insertnameserver(uuid,character varying,smallint) line 3
 at SQL statement
         at

org.springframework.jdbc.support.SQLErrorCodeSQLExceptionTranslator.doTranslate(SQLErrorCodeSQLExceptionTranslator.java:239)
 [spring-jdbc.jar:4.3.9.RELEASE]
         at

org.springframework.jdbc.support.AbstractFallbackSQLExceptionTranslator.translate(AbstractFallbackSQLExceptionTranslator.java:73)
 [spring-jdbc.jar:4.3.9.RELEASE]
         at
 org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:1099)
 [spring-jdbc.jar:4.3.9.RELEASE]
         at
 org.springframework.jdbc.core.JdbcTemplate.call(JdbcTemplate.java:1135)
 [spring-jdbc.jar:4.3.9.RELEASE]
         at

org.springframework.jdbc.core.simple.AbstractJdbcCall.executeCallInternal(AbstractJdbcCall.java:405)
 [spring-jdbc.jar:4.3.9.RELEASE]
         at

org.springframework.jdbc.core.simple.AbstractJdbcCall.doExecute(AbstractJdbcCall.java:365)
 [spring-jdbc.jar:4.3.9.RELEASE]
         at
 org.springframework.jdbc.core.simple.SimpleJdbcCall.execute(SimpleJdbcCall.java:198)
 [spring-jdbc.jar:4.3.9.RELEASE]
         at

org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeImpl(SimpleJdbcCallsHandler.java:135)
 [dal.jar:]
         at

org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeImpl(SimpleJdbcCallsHandler.java:130)
 [dal.jar:]
         at

org.ovirt.engine.core.dal.dbbroker.SimpleJdbcCallsHandler.executeModification(SimpleJdbcCallsHandler.java:76)
 [dal.jar:]
         at

org.ovirt.engine.core.dao.network.DnsResolverConfigurationDaoImpl.saveNameServersByDnsResolverConfigurationId(DnsResolverConfigurationDaoImpl.java:66)
 [dal.jar:]
         at

org.ovirt.engine.core.dao.network.DnsResolverConfigurationDaoImpl.update(DnsResolverConfigurationDaoImpl.java:110)
 [dal.jar:]
         at

org.ovirt.engine.core.dao.network.DnsResolverConfigurationDaoImpl.update(DnsResolverConfigurationDaoImpl.java:15)
 [dal.jar:]
         at

org.ovirt.engine.core.dao.VdsDynamicDaoImpl.updateDnsResolverConfiguration(VdsDynamicDaoImpl.java:156)
 [dal.jar:]
         at
 org.ovirt.engine.core.dao.VdsDynamicDaoImpl.update(VdsDynamicDaoImpl.java:145)
 [dal.jar:]
         at
 org.ovirt.engine.core.dao.VdsDynamicDaoImpl.updateIfNeeded(VdsDynamicDaoImpl.java:343)
 [dal.jar:]
         at
 org.ovirt.engine.core.dao.VdsDynamicDaoImpl.updateIfNeeded(VdsDynamicDaoImpl.java:35)
 [dal.jar:]
         at
 org.ovirt.engine.core.vdsbroker.VdsManager.updateDynamicData(VdsManager.java:473)
 [vdsbroker.jar:]
         at
 org.ovirt.engine.core.vdsbroker.VdsManager.updatePendingData(VdsManager.java:511)
 [vdsbroker.jar:]
         at

org.ovirt.engine.core.bll.scheduling.pending.PendingResourceManager.notifyHostManagers(PendingResourceManager.java:227)
 [bll.jar:]
         at

org.ovirt.engine.core.bll.scheduling.SchedulingManager.schedule(SchedulingManager.java:361)
 [bll.jar:]
         at
 org.ovirt.engine.core.bll.RunVmCommand.getVdsToRunOn(RunVmCommand.java:853)
 [bll.jar:]
         at
 org.ovirt.engine.core.bll.RunVmCommand.runVm(RunVmCommand.java:256)
 [bll.jar:]
         at
 org.ovirt.engine.core.bll.RunVmCommand.perform(RunVmCommand.java:430)
 [bll.jar:]
         at
 org.ovirt.engine.core.bll.RunVmCommand.executeVmCommand(RunVmCommand.java:355)
 [bll.jar:]
         at
 org.ovirt.engine.core.bll.VmCommand.executeCommand(VmCommand.java:161)
 [bll.jar:]
         at
 org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1133)
 [bll.jar:]
         at

org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1285)
 [bll.jar:]
         at
 org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1934)
 [bll.jar:]
         at

org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:164)
 [utils.jar:]
         at

org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:103)
 [utils.jar:]
         at
 org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1345)
 [bll.jar:]
         at
 org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:400)
 [bll.jar:]
         at

org.ovirt.engine.core.bll.executor.DefaultBackendActionExecutor.execute(DefaultBackendActionExecutor.java:13)
 [bll.jar:]
         at org.ovirt.engine.core.bll.Backend.runAction(Backend.java:468)
 [bll.jar:]
         at org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:450)
 [bll.jar:]
         at org.ovirt.engine.core.bll.Backend.runAction(Backend.java:403)
 [bll.jar:]
         at sun.reflect.GeneratedMethodAccessor134.invoke(Unknown Source)
 [:1.8.0_161]
         at
 sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
 [rt.jar:1.8.0_161]
         at java.lang.reflect.Method.invoke(Method.java:498)
 [rt.jar:1.8.0_161]
         at

org.jboss.as.ee.component.ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptor.java:52)
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
 at
 org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509)
         at

org.jboss.as.weld.ejb.DelegatingInterceptorInvocationContext.proceed(DelegatingInterceptorInvocationContext.java:92)
 [wildfly-weld-ejb-11.0.0.Final.jar:11.0.0.Final]
         at

org.jboss.weld.interceptor.proxy.WeldInvocationContext.interceptorChainCompleted(WeldInvocationContext.java:98)
 [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
         at

org.jboss.weld.interceptor.proxy.WeldInvocationContext.proceed(WeldInvocationContext.java:117)
 [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
         at

org.ovirt.engine.core.common.di.interceptor.LoggingInterceptor.apply(LoggingInterceptor.java:12)
 [common.jar:]
         at sun.reflect.GeneratedMethodAccessor69.invoke(Unknown Source)
 [:1.8.0_161]
         at
 sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
 [rt.jar:1.8.0_161]
         at java.lang.reflect.Method.invoke(Method.java:498)
 [rt.jar:1.8.0_161]
         at

org.jboss.weld.interceptor.reader.SimpleInterceptorInvocation$SimpleMethodInvocation.invoke(SimpleInterceptorInvocation.java:73)
 [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
         at

org.jboss.weld.interceptor.proxy.WeldInvocationContext.invokeNext(WeldInvocationContext.java:83)
 [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
         at

org.jboss.weld.interceptor.proxy.WeldInvocationContext.proceed(WeldInvocationContext.java:115)
 [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
         at
 org.jboss.weld.bean.InterceptorImpl.intercept(InterceptorImpl.java:108)
 [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
         at

org.jboss.as.weld.ejb.DelegatingInterceptorInvocationContext.proceed(DelegatingInterceptorInvocationContext.java:82)
 [wildfly-weld-ejb-11.0.0.Final.jar:11.0.0.Final]
         at

org.jboss.as.weld.interceptors.EjbComponentInterceptorSupport.delegateInterception(EjbComponentInterceptorSupport.java:60)
         at

org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:76)
         at

org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:88)
         at

org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:101)
         at

org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at
 org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509)
         at

org.ovirt.engine.core.bll.interceptors.CorrelationIdTrackerInterceptor.aroundInvoke(CorrelationIdTrackerInterceptor.java:13)
 [bll.jar:]
         at sun.reflect.GeneratedMethodAccessor66.invoke(Unknown Source)
 [:1.8.0_161]
         at
 sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
 [rt.jar:1.8.0_161]
         at java.lang.reflect.Method.invoke(Method.java:498)
 [rt.jar:1.8.0_161]
         at

org.jboss.as.ee.component.ManagedReferenceLifecycleMethodInterceptor.processInvocation(ManagedReferenceLifecycleMethodInterceptor.java:89)
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ejb3.component.invocationmetrics.ExecutionTimeInterceptor.processInvocation(ExecutionTimeInterceptor.java:43)
 [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ee.concurrent.ConcurrentContextInterceptor.processInvocation(ConcurrentContextInterceptor.java:45)
 [wildfly-ee-11.0.0.Final.jar:11.0.0.Final]
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at
 org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:40)
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at
 org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53)
         at

org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:52)
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53)
 [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at
 org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:264)
 [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
         at
 org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:379)
 [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
         at
 org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:244)
 [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at
 org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509)
         at

org.jboss.weld.ejb.AbstractEJBRequestScopeActivationInterceptor.aroundInvoke(AbstractEJBRequestScopeActivationInterceptor.java:73)
 [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
         at

org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:89)
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41)
 [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor.processInvocation(WaitTimeInterceptor.java:47)
 [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ejb3.security.SecurityContextInterceptor.processInvocation(SecurityContextInterceptor.java:100)
 [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ejb3.deployment.processors.StartupAwaitInterceptor.processInvocation(StartupAwaitInterceptor.java:22)
 [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:64)
 [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:67)
 [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
   at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50)
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.invocation.ContextClassLoaderInterceptor.processInvocation(ContextClassLoaderInterceptor.java:60)
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at
 org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:438)
         at

org.wildfly.security.manager.WildFlySecurityManager.doChecked(WildFlySecurityManager.java:609)
         at

org.jboss.invocation.AccessCheckingInterceptor.processInvocation(AccessCheckingInterceptor.java:57)
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at
 org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53)
         at
 org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:198)
         at
 org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:185)
         at
 org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:81)
         at
 org.ovirt.engine.core.common.interfaces.BackendLocal$$$view2.runAction(Unknown
 Source) [common.jar:]
         at
 org.ovirt.engine.api.restapi.resource.BackendResource.doAction(BackendResource.java:250)
         at

org.ovirt.engine.api.restapi.resource.AbstractBackendActionableResource.doAction(AbstractBackendActionableResource.java:84)
         at

org.ovirt.engine.api.restapi.resource.AbstractBackendActionableResource.doAction(AbstractBackendActionableResource.java:125)
         at

org.ovirt.engine.api.restapi.resource.BackendVmResource.start(BackendVmResource.java:450)
         at org.ovirt.engine.api.v3.V3Server.adaptAction(V3Server.java:217)
         at
 org.ovirt.engine.api.v3.servers.V3VmServer.start(V3VmServer.java:216)
         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
 [rt.jar:1.8.0_161]
         at
 sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
 [rt.jar:1.8.0_161]
         at
 sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
 [rt.jar:1.8.0_161]
         at java.lang.reflect.Method.invoke(Method.java:498)
 [rt.jar:1.8.0_161]
         at
 org.jboss.resteasy.core.MethodInjectorImpl.invoke(MethodInjectorImpl.java:140)
 [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
         at

org.jboss.resteasy.core.ResourceMethodInvoker.invokeOnTarget(ResourceMethodInvoker.java:295)
 [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
         at
 org.jboss.resteasy.core.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:249)
 [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
         at

org.jboss.resteasy.core.ResourceLocatorInvoker.invokeOnTargetObject(ResourceLocatorInvoker.java:138)
 [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
         at
 org.jboss.resteasy.core.ResourceLocatorInvoker.invoke(ResourceLocatorInvoker.java:107)
 [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
         at

org.jboss.resteasy.core.ResourceLocatorInvoker.invokeOnTargetObject(ResourceLocatorInvoker.java:133)
 [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
         at
 org.jboss.resteasy.core.ResourceLocatorInvoker.invoke(ResourceLocatorInvoker.java:101)
 [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
         at
 org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:406)
 [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
         at
 org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:213)
 [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
         at

org.jboss.resteasy.plugins.server.servlet.ServletContainerDispatcher.service(ServletContainerDispatcher.java:228)
 [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
         at

org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.service(HttpServletDispatcher.java:56)
 [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
         at

org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.service(HttpServletDispatcher.java:51)
 [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
         at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
 [jboss-servlet-api_3.1_spec-1.0.0.Final.jar:1.0.0.Final]
         at
 io.undertow.servlet.handlers.ServletHandler.handleRequest(ServletHandler.java:85)
         at
 io.undertow.servlet.handlers.FilterHandler.handleRequest(FilterHandler.java:81)
         at

io.undertow.servlet.handlers.security.ServletSecurityRoleHandler.handleRequest(ServletSecurityRoleHandler.java:62)
         at

io.undertow.servlet.handlers.ServletDispatchingHandler.handleRequest(ServletDispatchingHandler.java:36)
         at
 io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
         at
 io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
         at
 io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
         at

io.undertow.servlet.handlers.ServletInitialHandler.dispatchRequest(ServletInitialHandler.java:274)
         at

io.undertow.servlet.handlers.ServletInitialHandler.dispatchToPath(ServletInitialHandler.java:209)
         at

io.undertow.servlet.spec.RequestDispatcherImpl.forwardImpl(RequestDispatcherImpl.java:221)
         at

io.undertow.servlet.spec.RequestDispatcherImpl.forwardImplSetup(RequestDispatcherImpl.java:147)
         at
 io.undertow.servlet.spec.RequestDispatcherImpl.forward(RequestDispatcherImpl.java:111)
         at
 org.ovirt.engine.api.restapi.invocation.VersionFilter.doFilter(VersionFilter.java:180)
         at
 org.ovirt.engine.api.restapi.invocation.VersionFilter.doFilter(VersionFilter.java:98)
         at
 io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
         at

io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
         at
 org.ovirt.engine.api.restapi.invocation.CurrentFilter.doFilter(CurrentFilter.java:117)
         at
 org.ovirt.engine.api.restapi.invocation.CurrentFilter.doFilter(CurrentFilter.java:72)
         at
 io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
   at

io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
         at

org.ovirt.engine.core.aaa.filters.RestApiSessionMgmtFilter.doFilter(RestApiSessionMgmtFilter.java:78)
 [aaa.jar:]
         at
 io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
         at

io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
         at
 org.ovirt.engine.core.aaa.filters.EnforceAuthFilter.doFilter(EnforceAuthFilter.java:42)
 [aaa.jar:]
         at
 io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
         at

io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
         at

org.ovirt.engine.core.aaa.filters.SsoRestApiNegotiationFilter.doFilter(SsoRestApiNegotiationFilter.java:84)
 [aaa.jar:]
         at
 io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
         at

io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
         at

org.ovirt.engine.core.aaa.filters.SsoRestApiAuthFilter.doFilter(SsoRestApiAuthFilter.java:47)
 [aaa.jar:]
         at
 io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
         at

io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
         at

org.ovirt.engine.core.aaa.filters.SessionValidationFilter.doFilter(SessionValidationFilter.java:59)
 [aaa.jar:]
         at
 io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
         at

io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
         at

org.ovirt.engine.core.aaa.filters.RestApiSessionValidationFilter.doFilter(RestApiSessionValidationFilter.java:35)
 [aaa.jar:]
         at
 io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
         at

io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
         at

org.ovirt.engine.api.restapi.security.CSRFProtectionFilter.doFilter(CSRFProtectionFilter.java:111)
         at

org.ovirt.engine.api.restapi.security.CSRFProtectionFilter.doFilter(CSRFProtectionFilter.java:102)
         at
 io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
         at

io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
         at

org.ovirt.engine.core.utils.servlet.CORSSupportFilter.doFilter(CORSSupportFilter.java:284)
 [utils.jar:]
         at
 io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
         at

io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
         at
 io.undertow.servlet.handlers.FilterHandler.handleRequest(FilterHandler.java:84)
         at

io.undertow.servlet.handlers.security.ServletSecurityRoleHandler.handleRequest(ServletSecurityRoleHandler.java:62)
         at

io.undertow.servlet.handlers.ServletDispatchingHandler.handleRequest(ServletDispatchingHandler.java:36)
         at

org.wildfly.extension.undertow.security.SecurityContextAssociationHandler.handleRequest(SecurityContextAssociationHandler.java:78)
         at
 io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
         at

io.undertow.servlet.handlers.security.SSLInformationAssociationHandler.handleRequest(SSLInformationAssociationHandler.java:131)
         at

io.undertow.servlet.handlers.security.ServletAuthenticationCallHandler.handleRequest(ServletAuthenticationCallHandler.java:57)
         at
 io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
         at

io.undertow.security.handlers.AuthenticationConstraintHandler.handleRequest(AuthenticationConstraintHandler.java:53)
         at

io.undertow.security.handlers.AbstractConfidentialityHandler.handleRequest(AbstractConfidentialityHandler.java:46)
         at

io.undertow.servlet.handlers.security.ServletConfidentialityConstraintHandler.handleRequest(ServletConfidentialityConstraintHandler.java:64)
         at

io.undertow.servlet.handlers.security.ServletSecurityConstraintHandler.handleRequest(ServletSecurityConstraintHandler.java:59)
         at

io.undertow.security.handlers.AuthenticationMechanismsHandler.handleRequest(AuthenticationMechanismsHandler.java:60)
         at

io.undertow.servlet.handlers.security.CachedAuthenticatedSessionHandler.handleRequest(CachedAuthenticatedSessionHandler.java:77)
         at

io.undertow.security.handlers.NotificationReceiverHandler.handleRequest(NotificationReceiverHandler.java:50)
         at

io.undertow.security.handlers.AbstractSecurityContextAssociationHandler.handleRequest(AbstractSecurityContextAssociationHandler.java:43)
         at
 io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
         at

org.wildfly.extension.undertow.security.jacc.JACCContextIdHandler.handleRequest(JACCContextIdHandler.java:61)
         at
 io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
         at
 io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
         at

io.undertow.servlet.handlers.ServletInitialHandler.handleFirstRequest(ServletInitialHandler.java:292)
         at

io.undertow.servlet.handlers.ServletInitialHandler.access$100(ServletInitialHandler.java:81)
         at

io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:138)
         at

io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:135)
         at

io.undertow.servlet.core.ServletRequestContextThreadSetupAction$1.call(ServletRequestContextThreadSetupAction.java:48)
         at

io.undertow.servlet.core.ContextClassLoaderSetupAction$1.call(ContextClassLoaderSetupAction.java:43)
         at

org.wildfly.extension.undertow.security.SecurityContextThreadSetupAction.lambda$create$0(SecurityContextThreadSetupAction.java:105)
         at

org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1508)
         at

org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1508)
         at

org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1508)
         at

io.undertow.servlet.handlers.ServletInitialHandler.dispatchRequest(ServletInitialHandler.java:272)
         at

io.undertow.servlet.handlers.ServletInitialHandler.access$000(ServletInitialHandler.java:81)
    at

io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:138)
         at

io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:135)
         at

io.undertow.servlet.core.ServletRequestContextThreadSetupAction$1.call(ServletRequestContextThreadSetupAction.java:48)
         at

io.undertow.servlet.core.ContextClassLoaderSetupAction$1.call(ContextClassLoaderSetupAction.java:43)
         at

org.wildfly.extension.undertow.security.SecurityContextThreadSetupAction.lambda$create$0(SecurityContextThreadSetupAction.java:105)
         at

org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1508)
         at

org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1508)
         at

org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1508)
         at

io.undertow.servlet.handlers.ServletInitialHandler.dispatchRequest(ServletInitialHandler.java:272)
         at

io.undertow.servlet.handlers.ServletInitialHandler.access$000(ServletInitialHandler.java:81)
         at

io.undertow.servlet.handlers.ServletInitialHandler$1.handleRequest(ServletInitialHandler.java:104)
         at
 io.undertow.server.Connectors.executeRootHandler(Connectors.java:326)
         at
 io.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:812)
         at
 java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
 [rt.jar:1.8.0_161]
         at
 java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
 [rt.jar:1.8.0_161]
         at java.lang.Thread.run(Thread.java:748) [rt.jar:1.8.0_161]
 Caused by: org.postgresql.util.PSQLException: ERROR: duplicate key value
 violates unique constraint "name_server_pkey"
   Detail: Key (dns_resolver_configuration_id,
 address)=(8a941b6a-83aa-44de-9800-2a1ea6e8e029, 192.168.200.1) already
 exists.
   Where: SQL statement "INSERT INTO
     name_server(
       address,
       position,
       dns_resolver_configuration_id)
     VALUES (
       v_address,
       v_position,
       v_dns_resolver_configuration_id)"
 PL/pgSQL function insertnameserver(uuid,character varying,smallint) line 3
 at SQL statement
         at

org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2157)
         at
 org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1886)
         at
 org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:255)
         at
 org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:555)
         at

org.postgresql.jdbc2.AbstractJdbc2Statement.executeWithFlags(AbstractJdbc2Statement.java:417)
         at
 org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:410)
         at

org.jboss.jca.adapters.jdbc.CachedPreparedStatement.execute(CachedPreparedStatement.java:303)
         at

org.jboss.jca.adapters.jdbc.WrappedPreparedStatement.execute(WrappedPreparedStatement.java:442)
         at

org.springframework.jdbc.core.JdbcTemplate$6.doInCallableStatement(JdbcTemplate.java:1138)
 [spring-jdbc.jar:4.3.9.RELEASE]
         at

org.springframework.jdbc.core.JdbcTemplate$6.doInCallableStatement(JdbcTemplate.java:1135)
 [spring-jdbc.jar:4.3.9.RELEASE]
         at
 org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:1083)
 [spring-jdbc.jar:4.3.9.RELEASE]
         ... 215 more

 2018-03-07 17:03:38,453-05 DEBUG
 [org.ovirt.engine.core.dal.dbbroker.BatchProcedureExecutionConnectionCallback]
 (EE-ManagedThreadFactory-engineScheduled-Thread-82) [] Executing batch for
 procedure Updatevds_interface_statistics
 2018-03-07 17:03:38,453-05 DEBUG
 [org.ovirt.engine.core.dal.dbbroker.BatchProcedureExecutionConnectionCallback]
 (EE-ManagedThreadFactory-engineScheduled-Thread-82) [] Mapped params:
 [v_iface_status, v_sample_time, v_rx_offset, v_rx_rate, v_tx_rate,
 v_tx_drop, v_tx_offset, v_id, v_rx_drop, v_rx_total, v_tx_total, v_vds_id]
 2018-03-07 17:03:38,453-05 DEBUG
 [org.ovirt.engine.core.dal.dbbroker.BatchProcedureExecutionConnectionCallback]
 (EE-ManagedThreadFactory-engineScheduled-Thread-82) [] Mapped params:
 [v_iface_status, v_sample_time, v_rx_offset, v_rx_rate, v_tx_rate,
 v_tx_drop, v_tx_offset, v_id, v_rx_drop, v_rx_total, v_tx_total, v_vds_id]
 2018-03-07 17:03:38,453-05 DEBUG
 [org.ovirt.engine.core.dal.dbbroker.BatchProcedureExecutionConnectionCallback]
 (EE-ManagedThreadFactory-engineScheduled-Thread-82) [] Mapped params:
 [v_iface_status, v_sample_time, v_rx_offset, v_rx_rate, v_tx_rate,
 v_tx_drop, v_tx_offset, v_id, v_rx_drop, v_rx_total, v_tx_total, v_vds_id]
 2018-03-07 17:03:38,453-05 DEBUG
 [org.ovirt.engine.core.dal.dbbroker.BatchProcedureExecutionConnectionCallback]
 (EE-ManagedThreadFactory-engineScheduled-Thread-82) [] Mapped params:
 [v_iface_status, v_sample_time, v_rx_offset, v_rx_rate, v_tx_rate,
 v_tx_drop, v_tx_offset, v_id, v_rx_drop, v_rx_total, v_tx_total, v_vds_id]
 2018-03-07 17:03:38,453-05 DEBUG
 [org.ovirt.engine.core.dal.dbbroker.BatchProcedureExecutionConnectionCallback]
 (EE-ManagedThreadFactory-engineScheduled-Thread-82) [] Mapped params:
 [v_iface_status, v_sample_time, v_rx_offset, v_rx_rate, v_tx_rate,
 v_tx_drop, v_tx_offset, v_id, v_rx_drop, v_rx_total, v_tx_total, v_vds_id]
 2018-03-07 17:03:38,456-05 DEBUG
 [org.ovirt.engine.core.dal.dbbroker.BatchProcedureExecutionConnectionCallback]
 (EE-ManagedThreadFactory-engineScheduled-Thread-82) [] Executed batch
 2018-03-07 17:03:38,457-05 DEBUG
 [org.ovirt.engine.core.dal.dbbroker.BatchProcedureExecutionConnectionCallback]
 (EE-ManagedThreadFactory-engineScheduled-Thread-82) [] Executing batch for
 procedure UpdateNumaNodeStatistics
 2018-03-07 17:03:38,457-05 DEBUG
 [org.ovirt.engine.core.dal.dbbroker.BatchProcedureExecutionConnectionCallback]
 (EE-ManagedThreadFactory-engineScheduled-Thread-82) [] Mapped params:
 [v_numa_node_id, v_mem_free, v_usage_mem_percent, v_cpu_sys, v_cpu_user,
 v_cpu_idle, v_usage_cpu_percent]
 2018-03-07 17:03:38,459-05 DEBUG
 [org.ovirt.engine.core.dal.dbbroker.BatchProcedureExecutionConnectionCallback]
 (EE-ManagedThreadFactory-engineScheduled-Thread-82) [] Executed batch
 2018-03-07 17:03:38,462-05 ERROR
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
 (default task-21) [a514da44-d44f-46b9-8bdf-08ba2d513929] EVENT_ID:
 USER_FAILED_RUN_VM(54), Failed to run VM backup_vm  (User: admin@in:
 2018-03-07 17:03:38,466-05 DEBUG
 [org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil]
 (EE-ManagedThreadFactory-engine-Thread-182) [] Executing task:
 EE-ManagedThreadFactory-engine-Thread-182
 2018-03-07 17:03:38,467-05 INFO
 [org.ovirt.engine.core.bll.RunVmOnceCommand] (default task-21)
 [a514da44-d44f-46b9-8bdf-08ba2d513929] Lock freed to object
 'EngineLock:{exclusiveLocks='[634c6a46-d057-4509-be3b-710716cbd56d=VM]',
 sharedLocks=''}'
 2018-03-07 17:03:38,467-05 DEBUG
 [org.ovirt.engine.core.common.di.interceptor.DebugLoggingInterceptor]
 (default task-21) [a514da44-d44f-46b9-8bdf-08ba2d513929] method: runAction,
 params: [RunVmOnce,
 RunVmOnceParams:{commandId='d5358409-e005-4c8b-a702-d4fa394ee9d2',
 user='null', commandType='Unknown',
 vmId='634c6a46-d057-4509-be3b-710716cbd56d'}], timeElapsed: 201ms
 2018-03-07 17:03:38,473-05 ERROR
 [org.ovirt.engine.api.restapi.resource.AbstractBackendResource] (default
 task-21) [] Operation Failed: [Internal Engine Error]

 </error>

 _______________________________________________
 Devel mailing list
 Devel(a)ovirt.org
 http://lists.ovirt.org/mailman/listinfo/devel 

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

Re: [ovirt-devel] [ OST Failure Report ] [ oVirt 4.2 (ovirt-hosted-engine-setup) ] [ 08-03-2018 ] [ 004_basic_sanity.run_vms ]