[ OST Failure Report ] [ oVirt master ] [ 12-12-2017 ] [ 098_ovirt_provider_ovn.use_ovn_provider ]
Dafna Ron
dron at redhat.com
Wed Dec 13 11:05:59 UTC 2017
Thanks for the help and quick fix!
Dafna
On 12/13/2017 09:41 AM, Milan Zamazal wrote:
> Yaniv Kaul <ykaul at redhat.com> writes:
>
>> On Tue, Dec 12, 2017 at 10:11 PM, Yaniv Kaul <ykaul at redhat.com> wrote:
>>
>>> I suspect you've missed the relevant exception:
>>> {"jsonrpc": "2.0", "id": "05aaf760-b3b7-47b5-8bd7-034e899b7400", "error":
>>> {"message": "General Exception: (\"'portMirroring'\",)", "code": 100}}�
>>> 2017-12-12 13:27:48,142-05 DEBUG [org.ovirt.vdsm.jsonrpc.client.internal.ResponseWorker]
>>> (ResponseWorker) [] Message received: {"jsonrpc": "2.0", "id":
>>> "05aaf760-b3b7-47b5-8bd7-034e899b7400", "error": {"message": "General
>>> Exception: (\"'portMirroring'\",)", "code": 100}}
>>> 2017-12-12 13:27:48,142-05 ERROR [org.ovirt.engine.core.
>>> vdsbroker.vdsbroker.HotUnplugNicVDSCommand] (default task-12) [4c4b50dc]
>>> Failed in 'HotUnplugNicVDS' method
>>> 2017-12-12 13:27:48,150-05 ERROR [org.ovirt.engine.core.dal.
>>> dbbroker.auditloghandling.AuditLogDirector] (default task-12) [4c4b50dc]
>>> EVENT_ID: VDS_BROKER_COMMAND_FAILURE(10,802), VDSM
>>> lago-basic-suite-master-host-0 command HotUnplugNicVDS failed: General
>>> Exception: ("'portMirroring'",)
>>> 2017-12-12 13:27:48,150-05 INFO [org.ovirt.engine.core.
>>> vdsbroker.vdsbroker.HotUnplugNicVDSCommand] (default task-12) [4c4b50dc]
>>> Command 'org.ovirt.engine.core.vdsbroker.vdsbroker.HotUnplugNicVDSCommand'
>>> return value 'StatusOnlyReturn [status=Status [code=100, message=General
>>> Exception: ("'portMirroring'",)]]'
>>> 2017-12-12 13:27:48,150-05 INFO [org.ovirt.engine.core.
>>> vdsbroker.vdsbroker.HotUnplugNicVDSCommand] (default task-12) [4c4b50dc]
>>> HostName = lago-basic-suite-master-host-0
>>> 2017-12-12 13:27:48,150-05 ERROR [org.ovirt.engine.core.
>>> vdsbroker.vdsbroker.HotUnplugNicVDSCommand] (default task-12) [4c4b50dc]
>>> Command 'HotUnplugNicVDSCommand(HostName = lago-basic-suite-master-host-0,
>>> VmNicDeviceVDSParameters:{hostId='cf1d46b2-4594-4076-9d24-6f75220f0c97',
>>> vm.vm_name='vm0', nic='VmNic:{id='4487559e-7e47-4ab7-a6fe-1ae32fb34336',
>>> vnicProfileId='623c894e-234c-4f1b-899e-275be6864f75', speed='1000',
>>> type='3', macAddress='00:1a:4a:16:01:03', linked='true',
>>> vmId='90124564-04f7-4607-a222-503c42262844', vmTemplateId='null'}',
>>> vmDevice='VmDevice:{id='VmDeviceId:{deviceId='4487559e-7e47-4ab7-a6fe-1ae32fb34336',
>>> vmId='90124564-04f7-4607-a222-503c42262844'}', device='bridge',
>>> type='INTERFACE', specParams='[inbound={}, outbound={}]',
>>> address='{slot=0x0a, bus=0x00, domain=0x0000, type=pci, function=0x0}',
>>> managed='true', plugged='true', readOnly='false', deviceAlias='net2',
>>> customProperties='[]', snapshotId='null', logicalName='null',
>>> hostDevice='null'}'})' execution failed: VDSGenericException:
>>> VDSErrorException: Failed to HotUnplugNicVDS, error = General Exception:
>>> ("'portMirroring'",), code = 100
>>> 2017-12-12 13:27:48,150-05 DEBUG [org.ovirt.engine.core.
>>> vdsbroker.vdsbroker.HotUnplugNicVDSCommand] (default task-12) [4c4b50dc]
>>> Exception: org.ovirt.engine.core.vdsbroker.vdsbroker.VDSErrorException:
>>> VDSGenericException: VDSErrorException: Failed to HotUnplugNicVDS, error =
>>> General Exception: ("'portMirroring'",), code = 100
>>> at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.
>>> createDefaultConcreteException(VdsBrokerCommand.java:81) [vdsbroker.jar:]
>>> at org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase.
>>> createException(BrokerCommandBase.java:224) [vdsbroker.jar:]
>>> at org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase.
>>> proceedProxyReturnValue(BrokerCommandBase.java:194) [vdsbroker.jar:]
>>> at org.ovirt.engine.core.vdsbroker.vdsbroker.HotUnplugNicVDSCommand.
>>> executeVdsBrokerCommand(HotUnplugNicVDSCommand.java:14) [vdsbroker.jar:]
>>> at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.
>>> executeVDSCommand(VdsBrokerCommand.java:112) [vdsbroker.jar:]
>>> at org.ovirt.engine.core.vdsbroker.VDSCommandBase.
>>> executeCommand(VDSCommandBase.java:73) [vdsbroker.jar:]
>>> ...
>>>
>>>
>>> (I did not look into the VDSM log to see what failed there)
>>>
>> And now that I did[1]:
>> 2017-12-12 13:27:48,137-0500 INFO (jsonrpc/0) [api.virt] START
>> hotunplugNic(params={u'nic': {u'nicModel': u'pv', u'macAddr':
>> u'00:1a:4a:16:01:03', u'linkActive': u'true', u'network': u'network_1',
>> u'filterParameters': [], u'filter': u'vdsm-no-mac-spoofing', u'specParams':
>> {u'inbound': {}, u'outbound': {}}, u'deviceId':
>> u'4487559e-7e47-4ab7-a6fe-1ae32fb34336', u'address': {u'function': u'0x0',
>> u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'slot': u'0x0a'},
>> u'device': u'bridge', u'type': u'interface'}, u'vmId':
>> u'90124564-04f7-4607-a222-503c42262844'}) from=::ffff:192.168.202.4,47014,
>> flow_id=4c4b50dc (api:46)
>> 2017-12-12 13:27:48,137-0500 ERROR (jsonrpc/0) [api] FINISH hotunplugNic
>> error='portMirroring' (api:127)
>> Traceback (most recent call last):
>> File "/usr/lib/python2.7/site-packages/vdsm/common/api.py", line 117, in
>> method
>> ret = func(*args, **kwargs)
>> File "/usr/lib/python2.7/site-packages/vdsm/API.py", line 394, in
>> hotunplugNic
>> return self.vm.hotunplugNic(params)
>> File "<string>", line 2, in hotunplugNic
>> File "/usr/lib/python2.7/site-packages/vdsm/common/api.py", line 94, in
>> method
>> return func(*args, **kwargs)
>> File "/usr/lib/python2.7/site-packages/vdsm/virt/vm.py", line 3157, in
>> hotunplugNic
>> port_mirroring = nicParams['portMirroring']
>> KeyError: 'portMirroring'
>> 2017-12-12 13:27:48,141-0500 INFO (jsonrpc/0) [api.virt] FINISH
>> hotunplugNic return={'status': {'message': 'General Exception:
>> ("\'portMirroring\'",)', 'code': 100}} from=::ffff:192.168.202.4,47014,
>> flow_id=4c4b50dc (api:52)
> Thank you for the notice and analysis, fix posted: https://gerrit.ovirt.org/85381
>
>> Y.
>>
>> [1]
>> http://jenkins.ovirt.org/job/ovirt-master_change-queue-tester/4371/artifact/exported-artifacts/basic-suit-master-el7/test_logs/basic-suite-master/post-098_ovirt_provider_ovn.py/lago-basic-suite-master-host-1/_var_log/vdsm/vdsm.log
>>
>>
>>> Y.
>>>
>>>
>>> On Tue, Dec 12, 2017 at 9:51 PM, Dafna Ron <dron at redhat.com> wrote:
>>>
>>>> Hi,
>>>>
>>>> We have a failure on test 098_ovirt_provider_ovn.use_ovn_provider but I
>>>> am not sure how the reported patch is related to the failure.
>>>>
>>>> *Link and headline of suspected patches: *
>>>> * https://gerrit.ovirt.org/#/c/85147/
>>>> <https://gerrit.ovirt.org/#/c/85147/> - virt: Fix flipped condition in
>>>> hotunplugNic*
>>>>
>>>> *Link to Job:*
>>>> *http://jenkins.ovirt.org/job/ovirt-master_change-queue-tester/4371
>>>> <http://jenkins.ovirt.org/job/ovirt-master_change-queue-tester/4371>*
>>>>
>>>>
>>>> *Link to all logs:*
>>>> *
>>>> http://jenkins.ovirt.org/job/ovirt-master_change-queue-tester/4371/artifact
>>>> <http://jenkins.ovirt.org/job/ovirt-master_change-queue-tester/4371/artifact>*
>>>>
>>>>
>>>> *(Relevant) error snippet from the log: *
>>>>
>>>>
>>>>
>>>> * <error> from junit: *
>>>>
>>>>
>>>> File "/usr/lib64/python2.7/unittest/case.py", line 369, in run
>>>> testMethod()
>>>> File "/usr/lib/python2.7/site-packages/nose/case.py", line 197, in runTest
>>>> self.test(*self.arg)
>>>> File "/usr/lib/python2.7/site-packages/ovirtlago/testlib.py", line 129, in wrapped_test
>>>> test()
>>>> File "/usr/lib/python2.7/site-packages/ovirtlago/testlib.py", line 59, in wrapper
>>>> return func(get_test_prefix(), *args, **kwargs)
>>>> File "/usr/lib/python2.7/site-packages/ovirtlago/testlib.py", line 78, in wrapper
>>>> prefix.virt_env.engine_vm().get_api(api_ver=4), *args, **kwargs
>>>> File "/usr/lib/python2.7/site-packages/ovirtlago/testlib.py", line 59, in wrapper
>>>> return func(get_test_prefix(), *args, **kwargs)
>>>> File "/home/jenkins/workspace/ovirt-master_change-queue-tester/ovirt-system-tests/basic-suite-master/test-scenarios/098_ovirt_provider_ovn.py", line 435, in use_ovn_provider
>>>> _remove_iface_from_vm(api, VM0_NAME, IFACE_NAME)
>>>> File "/home/jenkins/workspace/ovirt-master_change-queue-tester/ovirt-system-tests/basic-suite-master/test-scenarios/098_ovirt_provider_ovn.py", line 388, in _remove_iface_from_vm
>>>> nic_service.deactivate()
>>>> File "/usr/lib64/python2.7/site-packages/ovirtsdk4/services.py", line 29110, in deactivate
>>>> return self._internal_action(action, 'deactivate', None, headers, query, wait)
>>>> File "/usr/lib64/python2.7/site-packages/ovirtsdk4/service.py", line 299, in _internal_action
>>>> return future.wait() if wait else future
>>>> File "/usr/lib64/python2.7/site-packages/ovirtsdk4/service.py", line 55, in wait
>>>> return self._code(response)
>>>> File "/usr/lib64/python2.7/site-packages/ovirtsdk4/service.py", line 296, in callback
>>>> self._check_fault(response)
>>>> File "/usr/lib64/python2.7/site-packages/ovirtsdk4/service.py", line 132, in _check_fault
>>>> self._raise_error(response, body)
>>>> File "/usr/lib64/python2.7/site-packages/ovirtsdk4/service.py", line 118, in _raise_error
>>>> raise error
>>>> 'Fault reason is "Operation Failed". Fault detail is "[General Exception]". HTTP response code is 400.\n-------------------- >> begin captured logging << --------------------\nrequests.packages.urllib3.connectionpool: INFO: * Starting new HTTPS connection (1): 192.168.202.4\npy.warnings: WARNING: * Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.org/en/latest/security.html\nrequests.packages.urllib3.connectionpool: <https://urllib3.readthedocs.org/en/latest/security.html/nrequests.packages.urllib3.connectionpool:> DEBUG: "POST /v2.0/tokens/ HTTP/1.1" 200 None\nrequests.packages.urllib3.connectionpool: INFO: * Starting new HTTPS connection (1): 192.168.202.4\nrequests.packages.urllib3.connectionpool: DEBUG: "GET /v2.0/networks/ HTTP/1.1" 200 None\nrequests.packages.urllib3.connectionpool: INFO: * Starting new HTTPS connection (1): 192.168.202.4\nrequests.packages.urllib3.connectionpool: DEBUG: "GET /v2.0/ports/ HTTP/1.1" 200 None\nrequests.packages.urllib3.connectionpool: INFO: * Starting new HTTPS connection (1): 192.168.202.4\nrequests.packages.urllib3.connectionpool: DEBUG: "GET /v2.0/subnets/ HTTP/1.1" 200 None\nrequests.packages.urllib3.connectionpool: INFO: * Starting new HTTPS connection (1): 192.168.202.4\nrequests.packages.urllib3.connectionpool: DEBUG: "POST /v2.0/networks/ HTTP/1.1" 201 None\nrequests.packages.urllib3.connectionpool: INFO: * Starting new HTTPS connection (1): 192.168.202.4\nrequests.packages.urllib3.connectionpool: DEBUG: "POST /v2.0/subnets/ HTTP/1.1" 201 None\nrequests.packages.urllib3.connectionpool: INFO: * Starting new HTTPS connection (1): 192.168.202.4\nrequests.packages.urllib3.connectionpool: DEBUG: "POST /v2.0/ports/ HTTP/1.1" 201 None\nrequests.packages.urllib3.connectionpool: INFO: * Starting new HTTPS connection (1): 192.168.202.4\nrequests.packages.urllib3.connectionpool: DEBUG: "GET /v2.0/networks/ HTTP/1.1" 200 None\nrequests.packages.urllib3.connectionpool: INFO: * Starting new HTTPS connection (1): 192.168.202.4\nrequests.packages.urllib3.connectionpool: DEBUG: "GET /v2.0/ports/ HTTP/1.1" 200 None\nrequests.packages.urllib3.connectionpool: INFO: * Starting new HTTPS connection (1): 192.168.202.4\nrequests.packages.urllib3.connectionpool: DEBUG: "GET /v2.0/subnets/ HTTP/1.1" 200 None\n--------------------- >> end captured logging << ---------------------'
>>>>
>>>> From engine:
>>>>
>>>> 2017-12-12 13:00:57,490-05 DEBUG [org.ovirt.engine.core.aaa.filters.SsoRestApiNegotiationFilter] (default task-6) [] SsoRestApiNegotiationFilter Not performing Negotiate Auth
>>>> 2017-12-12 13:00:57,548-05 DEBUG [org.ovirt.engine.core.common.di.interceptor.DebugLoggingInterceptor] (default task-6) [cdcdc9bf-0da4-4d64-953a-2c8e59587e2b] method: runQuery, params: [Search, SearchParameters:{refresh='false', filtered='false', searchType='Provider', searchPattern='Providers: type=OPENSTACK_NETWORK', caseSensitive='false', from='0', max='2147483647 <(214)%20748-3647>'}], timeElapsed: 44ms
>>>> 2017-12-12 13:00:57,586-05 DEBUG [org.ovirt.engine.core.dal.dbbroker.DbFacadeUtils] (default task-6) [cdcdc9bf-0da4-4d64-953a-2c8e59587e2b] Failed to decrypt password: javax.crypto.BadPaddingException: Decryption error
>>>> at sun.security.rsa.RSAPadding.unpadV15(RSAPadding.java:380) [rt.jar:1.8.0_151]
>>>> at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:291) [rt.jar:1.8.0_151]
>>>> at com.sun.crypto.provider.RSACipher.doFinal(RSACipher.java:363) [sunjce_provider.jar:1.8.0_151]
>>>> at com.sun.crypto.provider.RSACipher.engineDoFinal(RSACipher.java:389) [sunjce_provider.jar:1.8.0_151]
>>>> at javax.crypto.Cipher.doFinal(Cipher.java:2165) [jce.jar:1.8.0_151]
>>>> at org.ovirt.engine.core.utils.crypt.EngineEncryptionUtils.decrypt(EngineEncryptionUtils.java:179) [utils.jar:]
>>>> at org.ovirt.engine.core.dal.dbbroker.DbFacadeUtils.decryptPassword(DbFacadeUtils.java:35) [dal.jar:]
>>>> at org.ovirt.engine.core.dao.provider.ProviderDaoImpl$ProviderRowMapper.mapRow(ProviderDaoImpl.java:137) [dal.jar:]
>>>> at org.ovirt.engine.core.dao.provider.ProviderDaoImpl$ProviderRowMapper.mapRow(ProviderDaoImpl.java:119) [dal.jar:]
>>>> at org.springframework.jdbc.core.RowMapperResultSetExtractor.extractData(RowMapperResultSetExtractor.java:93) [spring-jdbc.jar:4.3.9.RELEASE]
>>>> at org.springframework.jdbc.core.RowMapperResultSetExtractor.extractData(RowMapperResultSetExtractor.java:60) [spring-jdbc.jar:4.3.9.RELEASE]
>>>> at org.springframework.jdbc.core.JdbcTemplate$1QueryStatementCallback.doInStatement(JdbcTemplate.java:463) [spring-jdbc.jar:4.3.9.RELEASE]
>>>> at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:408) [spring-jdbc.jar:4.3.9.RELEASE]
>>>> at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:474) [spring-jdbc.jar:4.3.9.RELEASE]
>>>> at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:484) [spring-jdbc.jar:4.3.9.RELEASE]
>>>> at org.ovirt.engine.core.dao.provider.ProviderDaoImpl.getAllWithQuery(ProviderDaoImpl.java:189) [dal.jar:]
>>>> at org.ovirt.engine.core.bll.SearchQuery.genericSearch(SearchQuery.java:343) [bll.jar:]
>>>> at org.ovirt.engine.core.bll.SearchQuery.searchProviders(SearchQuery.java:414) [bll.jar:]
>>>> at org.ovirt.engine.core.bll.SearchQuery.executeQueryCommand(SearchQuery.java:219) [bll.jar:]
>>>> at org.ovirt.engine.core.bll.QueriesCommandBase.executeCommand(QueriesCommandBase.java:106) [bll.jar:]
>>>> at org.ovirt.engine.core.dal.VdcCommandBase.execute(VdcCommandBase.java:33) [dal.jar:]
>>>> at org.ovirt.engine.core.bll.executor.DefaultBackendQueryExecutor.execute(DefaultBackendQueryExecutor.java:14) [bll.jar:]
>>>> at org.ovirt.engine.core.bll.Backend.runQueryImpl(Backend.java:579) [bll.jar:]
>>>> at org.ovirt.engine.core.bll.Backend.runQuery(Backend.java:548) [bll.jar:]
>>>> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.8.0_151]
>>>> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [rt.jar:1.8.0_151]
>>>> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_151]
>>>> at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_151]
>>>> 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_151]
>>>> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_151]
>>>> at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_151]
>>>> 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_151]
>>>> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_151]
>>>> at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_151]
>>>> 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.runQuery(Unknown Source) [common.jar:]
>>>> at org.ovirt.engine.api.restapi.resource.BackendResource.runQuery(BackendResource.java:82)
>>>> at org.ovirt.engine.api.restapi.resource.BackendResource.getBackendCollection(BackendResource.java:152)
>>>> at org.ovirt.engine.api.restapi.resource.AbstractBackendCollectionResource.getBackendCollection(AbstractBackendCollectionResource.java:48)
>>>> at org.ovirt.engine.api.restapi.resource.openstack.BackendOpenStackNetworkProvidersResource.getBackendCollection(BackendOpenStackNetworkProvidersResource.java:70)
>>>> at org.ovirt.engine.api.restapi.resource.openstack.BackendOpenStackNetworkProvidersResource.list(BackendOpenStackNetworkProvidersResource.java:50)
>>>> at org.ovirt.engine.api.resource.openstack.OpenstackNetworkProvidersResource.doList(OpenstackNetworkProvidersResource.java:52)
>>>> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.8.0_151]
>>>> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [rt.jar:1.8.0_151]
>>>> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_151]
>>>> at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_151]
>>>> 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: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$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_151]
>>>> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [rt.jar:1.8.0_151]
>>>> at java.lang.Thread.run(Thread.java:748) [rt.jar:1.8.0_151]
>>>>
>>>> 2017-12-12 13:00:57,599-05 DEBUG [org.ovirt.engine.core.common.di.interceptor.DebugLoggingInterceptor] (default task-6) [cdcdc9bf-0da4-4d64-953a-2c8e59587e2b] method: runQuery, params: [Search, SearchParameters:{refresh='false', filtered='false', searchType='Provider', searchPattern='Providers: type=EXTERNAL_NETWORK', caseSensitive='false', from='0', max='2147483647 <(214)%20748-3647>'}], timeElapsed: 51ms
>>>> 2017-12-12 13:00:57,604-05 DEBUG [org.ovirt.engine.core.aaa.filters.SsoRestApiAuthFilter] (default task-7) [] Entered SsoRestApiAuthFilter
>>>> 2017-12-12 13:00:57,605-05 DEBUG [org.ovirt.engine.core.aaa.filters.SsoRestApiAuthFilter] (default task-7) [] SsoRestApiAuthFilter authenticating with sso
>>>> 2017-12-12 13:00:57,605-05 DEBUG [org.ovirt.engine.core.aaa.filters.SsoRestApiAuthFilter] (default task-7) [] SsoRestApiAuthFilter authenticating using BEARER header
>>>> 2017-12-12 13:00:57,607-05 DEBUG [org.ovirt.engine.core.aaa.filters.SsoRestApiAuthFilter] (default task-7) [] SsoRestApiAuthFilter successfully authenticated using BEARER header
>>>> 2017-12-12 13:00:57,607-05 DEBUG [org.ovirt.engine.core.aaa.filters.SsoRestApiNegotiationFilter] (default task-7) [] Entered SsoRestApiNegotiationFilter
>>>> 2017-12-12 13:00:57,610-05 DEBUG [org.ovirt.engine.core.aaa.filters.SsoRestApiNegotiationFilter] (default task-7) [] SsoRestApiNegotiationFilter Not performing Negotiate Auth
>>>>
>>>> *</error>*
>>>>
>>>>
>>>> _______________________________________________
>>>> Devel mailing list
>>>> Devel at ovirt.org
>>>> http://lists.ovirt.org/mailman/listinfo/devel
>>>>
>>>
More information about the Infra
mailing list