Thanks for the help and quick fix!
Dafna
On 12/13/2017 09:41 AM, Milan Zamazal wrote:
Yaniv Kaul <ykaul(a)redhat.com> writes:
> On Tue, Dec 12, 2017 at 10:11 PM, Yaniv Kaul <ykaul(a)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/artifa...
>
>
>> Y.
>>
>>
>> On Tue, Dec 12, 2017 at 9:51 PM, Dafna Ron <dron(a)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/artifa...
>>>
>>>
>>> *(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.package...:
<
https://urllib3.readthedocs.org/en/latest/security.html/nrequests.package...
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(a)ovirt.org
>>>
http://lists.ovirt.org/mailman/listinfo/devel
>>>
>>