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

Martin Sivak msivak at redhat.com
Thu Mar 8 12:05:20 UTC 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 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/
>
> 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 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-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 at ovirt.org
> http://lists.ovirt.org/mailman/listinfo/devel


More information about the Infra mailing list