[ovirt-devel] [ OST Failure Report ] [ oVirt 4.2 (ovirt-hosted-engine-setup) ] [ 08-03-2018 ] [ 004_basic_sanity.run_vms ]
Yaniv Kaul
ykaul at redhat.com
Thu Mar 8 12:49:23 UTC 2018
On Thu, Mar 8, 2018 at 1:53 PM, Dafna Ron <dron at redhat.com> 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/
> <https://gerrit.ovirt.org/#/c/88622/>Link to
> Job:http://jenkins.ovirt.org/job/ovirt-4.2_change-queue-tester/1064/
> <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/
> <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-710716cbd56d 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 ADMIN2018-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], timeElapsed:
> 5ms2018-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-9da778f08082,
> Up], timeElapsed: 7ms2018-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], timeElapsed:
> 0ms2018-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', message [ERROR:
> duplicate key value violates unique constraint "name_server_pkey"*
>
A quick Google search on the above seems to point to
https://bugzilla.redhat.com/show_bug.cgi?id=1547070 , which says:
Steps to Reproduce:
1. Refresh caps during start VM operation
So might be relevant, I think.
Y.
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
> * 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 insertnameserver(?, ?, ?)}]; 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
> statement2018-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 insertnameserver(?, ?, ?)}]; 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 more2018-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_statistics2018-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
> batch2018-03-07 17:03:38,457-05 DEBUG
> [org.ovirt.engine.core.dal.dbbroker.BatchProcedureExecutionConnectionCallback]
> (EE-ManagedThreadFactory-engineScheduled-Thread-82) [] Executing batch for
> procedure UpdateNumaNodeStatistics2018-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
> batch2018-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 at 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-1822018-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: 201ms2018-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 at ovirt.org
> http://lists.ovirt.org/mailman/listinfo/devel
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ovirt.org/pipermail/infra/attachments/20180308/4b3ddb54/attachment-0001.html>
More information about the Infra
mailing list