Uploaded image for project: 'JBoss Transaction Manager'
  1. JBoss Transaction Manager
  2. JBTM-2883

Narayana should not hide exception traces even for internal or protocol errors in WS txn

XMLWordPrintable

    • Icon: Enhancement Enhancement
    • Resolution: Unresolved
    • Icon: Major Major
    • None
    • 5.5.6.Final
    • XTS

      Narayana hides stacktraces of exception when some internal error (e.g. protocol, communication...) happens in XTS. This particular problem reveales in case of customer case https://access.redhat.com/support/cases/#/case/01810386 where .NET application communicates with JBoss EAP over WS and WS-AT should be used. .NET (probably) uses non-standard header/message that caused Narayana response being null.

      The original error info was only

      ERROR [org.jboss.jbossts.txbridge] (http-/0.0.0.0:8543-1) com.arjuna.wst.SystemException: java.lang.NullPointerException
TRACE [org.jboss.jbossts.txbridge] (http-/0.0.0.0:8543-1) JaxWSTxInboundBridgeHandler.close()
INFO  [org.apache.cxf.services.IntegrationFrameworkServiceWsService.IntegrationFrameworkServiceWsPort.IntegrationFrameworkServiceWs] (http-/0.0.0.0:8543-1) Outbound Message

      which is quite not much to start tracking reason behind the problem.

      There should be shown stacktrace in log to get more detailed information for investigation to start. E.g. it should be something like

      09:21:36,155 ERROR [stderr] (http-0.0.0.0:8443-1) RICKY: response=null
09:21:36,156 ERROR [stderr] (http-0.0.0.0:8443-1) java.lang.NullPointerException
09:21:36,156 ERROR [stderr] (http-0.0.0.0:8443-1) 	at com.arjuna.wsc11.RegistrationCoordinator.register(RegistrationCoordinator.java:58)
09:21:36,156 ERROR [stderr] (http-0.0.0.0:8443-1) 	at com.arjuna.mwlabs.wst11.at.remote.TransactionManagerImple.registerParticipant(TransactionManagerImple.java:156)
09:21:36,156 ERROR [stderr] (http-0.0.0.0:8443-1) 	at com.arjuna.mwlabs.wst11.at.remote.TransactionManagerImple.enlistForDurableTwoPhase(TransactionManagerImple.java:41)
09:21:36,157 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.as.quickstarts.wsat.simple.RestaurantServiceATImpl.makeBooking(RestaurantServiceATImpl.java:74)
09:21:36,157 ERROR [stderr] (http-0.0.0.0:8443-1) 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
09:21:36,157 ERROR [stderr] (http-0.0.0.0:8443-1) 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
09:21:36,157 ERROR [stderr] (http-0.0.0.0:8443-1) 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
09:21:36,157 ERROR [stderr] (http-0.0.0.0:8443-1) 	at java.lang.reflect.Method.invoke(Method.java:498)
09:21:36,157 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.as.ee.component.ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptor.java:52)
09:21:36,157 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288)
09:21:36,157 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53)
09:21:36,157 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
09:21:36,157 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288)
09:21:36,157 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53)
09:21:36,157 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
09:21:36,158 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288)
09:21:36,158 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21)
09:21:36,158 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288)
09:21:36,158 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61)
09:21:36,158 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53)
09:21:36,158 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288)
09:21:36,158 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.as.webservices.deployers.WSComponentInstanceAssociationInterceptor.processInvocation(WSComponentInstanceAssociationInterceptor.java:49)
09:21:36,158 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288)
09:21:36,158 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61)
09:21:36,158 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:189)
09:21:36,158 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.as.webservices.invocation.AbstractInvocationHandler.invoke(AbstractInvocationHandler.java:129)
09:21:36,158 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.wsf.stack.cxf.JBossWSInvoker.performInvocation(JBossWSInvoker.java:177)
09:21:36,158 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.cxf.service.invoker.AbstractInvoker.invoke(AbstractInvoker.java:104)
09:21:36,159 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.cxf.jaxws.AbstractJAXWSMethodInvoker.invoke(AbstractJAXWSMethodInvoker.java:237)
09:21:36,159 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.cxf.jaxws.JAXWSMethodInvoker.invoke(JAXWSMethodInvoker.java:69)
09:21:36,159 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.wsf.stack.cxf.JBossWSInvoker.invoke(JBossWSInvoker.java:146)
09:21:36,159 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.cxf.interceptor.ServiceInvokerInterceptor$1.run(ServiceInvokerInterceptor.java:58)
09:21:36,159 ERROR [stderr] (http-0.0.0.0:8443-1) 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
09:21:36,159 ERROR [stderr] (http-0.0.0.0:8443-1) 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
09:21:36,159 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.cxf.workqueue.SynchronousExecutor.execute(SynchronousExecutor.java:37)
09:21:36,159 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.cxf.interceptor.ServiceInvokerInterceptor.handleMessage(ServiceInvokerInterceptor.java:107)
09:21:36,159 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:272)
09:21:36,159 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:121)
09:21:36,159 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDestination.java:249)
09:21:36,159 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.wsf.stack.cxf.RequestHandlerImpl.handleHttpRequest(RequestHandlerImpl.java:97)
09:21:36,159 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.wsf.stack.cxf.transport.ServletHelper.callRequestHandler(ServletHelper.java:131)
09:21:36,159 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.wsf.stack.cxf.CXFServletExt.invoke(CXFServletExt.java:88)
09:21:36,160 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest(AbstractHTTPServlet.java:289)
09:21:36,160 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doPost(AbstractHTTPServlet.java:209)
09:21:36,160 ERROR [stderr] (http-0.0.0.0:8443-1) 	at javax.servlet.http.HttpServlet.service(HttpServlet.java:754)
09:21:36,160 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.wsf.stack.cxf.CXFServletExt.service(CXFServletExt.java:136)
09:21:36,160 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.wsf.spi.deployment.WSFServlet.service(WSFServlet.java:140)
09:21:36,164 ERROR [stderr] (http-0.0.0.0:8443-1) 	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847)
09:21:36,164 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:295)
09:21:36,164 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:214)
09:21:36,165 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:231)
09:21:36,165 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:149)
09:21:36,165 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:512)
09:21:36,165 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:169)
09:21:36,165 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:150)
09:21:36,165 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:97)
09:21:36,165 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:102)
09:21:36,165 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:344)
09:21:36,165 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:854)
09:21:36,165 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:656)
09:21:36,165 ERROR [stderr] (http-0.0.0.0:8443-1) 	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:926)
09:21:36,165 ERROR [stderr] (http-0.0.0.0:8443-1) 	at java.lang.Thread.run(Thread.java:745)
09:21:36,167 ERROR [org.jboss.as.webservices.invocation.InvocationHandlerJAXWS] (http-0.0.0.0:8443-1) JBAS015594: Method invocation failed with exception: Error when enlisting participant: org.jboss.as.quickstarts.wsat.simple.RestaurantException: Error when enlisting participant
	at org.jboss.as.quickstarts.wsat.simple.RestaurantServiceATImpl.makeBooking(RestaurantServiceATImpl.java:76) [classes:]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.8.0_102]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [rt.jar:1.8.0_102]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_102]
	at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_102]
	at org.jboss.as.ee.component.ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptor.java:52) [jboss-as-ee-7.5.12.Final-redhat-1.jar:7.5.12.Final-redhat-1]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.3.Final-redhat-1.jar:1.1.3.Final-redhat-1]
	at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation-1.1.3.Final-redhat-1.jar:1.1.3.Final-redhat-1]
	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63) [jboss-as-ee-7.5.12.Final-redhat-1.jar:7.5.12.Final-redhat-1]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.3.Final-redhat-1.jar:1.1.3.Final-redhat-1]
	at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation-1.1.3.Final-redhat-1.jar:1.1.3.Final-redhat-1]
	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63) [jboss-as-ee-7.5.12.Final-redhat-1.jar:7.5.12.Final-redhat-1]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.3.Final-redhat-1.jar:1.1.3.Final-redhat-1]
	at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation-1.1.3.Final-redhat-1.jar:1.1.3.Final-redhat-1]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.3.Final-redhat-1.jar:1.1.3.Final-redhat-1]
	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.3.Final-redhat-1.jar:1.1.3.Final-redhat-1]
	at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee-7.5.12.Final-redhat-1.jar:7.5.12.Final-redhat-1]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.3.Final-redhat-1.jar:1.1.3.Final-redhat-1]
	at org.jboss.as.webservices.deployers.WSComponentInstanceAssociationInterceptor.processInvocation(WSComponentInstanceAssociationInterceptor.java:49)
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.3.Final-redhat-1.jar:1.1.3.Final-redhat-1]
	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.3.Final-redhat-1.jar:1.1.3.Final-redhat-1]
	at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:189) [jboss-as-ee-7.5.12.Final-redhat-1.jar:7.5.12.Final-redhat-1]
	at org.jboss.as.webservices.invocation.AbstractInvocationHandler.invoke(AbstractInvocationHandler.java:129)
	at org.jboss.wsf.stack.cxf.JBossWSInvoker.performInvocation(JBossWSInvoker.java:177)
	at org.apache.cxf.service.invoker.AbstractInvoker.invoke(AbstractInvoker.java:104)
	at org.apache.cxf.jaxws.AbstractJAXWSMethodInvoker.invoke(AbstractJAXWSMethodInvoker.java:237)
	at org.apache.cxf.jaxws.JAXWSMethodInvoker.invoke(JAXWSMethodInvoker.java:69)
	at org.jboss.wsf.stack.cxf.JBossWSInvoker.invoke(JBossWSInvoker.java:146)
	at org.apache.cxf.interceptor.ServiceInvokerInterceptor$1.run(ServiceInvokerInterceptor.java:58)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [rt.jar:1.8.0_102]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [rt.jar:1.8.0_102]
	at org.apache.cxf.workqueue.SynchronousExecutor.execute(SynchronousExecutor.java:37)
	at org.apache.cxf.interceptor.ServiceInvokerInterceptor.handleMessage(ServiceInvokerInterceptor.java:107)
	at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:272)
	at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:121)
	at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDestination.java:249)
	at org.jboss.wsf.stack.cxf.RequestHandlerImpl.handleHttpRequest(RequestHandlerImpl.java:97)
	at org.jboss.wsf.stack.cxf.transport.ServletHelper.callRequestHandler(ServletHelper.java:131)
	at org.jboss.wsf.stack.cxf.CXFServletExt.invoke(CXFServletExt.java:88)
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest(AbstractHTTPServlet.java:289)
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doPost(AbstractHTTPServlet.java:209)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:754) [jboss-servlet-api_3.0_spec-1.0.2.Final-redhat-2.jar:1.0.2.Final-redhat-2]
	at org.jboss.wsf.stack.cxf.CXFServletExt.service(CXFServletExt.java:136)
	at org.jboss.wsf.spi.deployment.WSFServlet.service(WSFServlet.java:140) [jbossws-spi-2.3.1.Final-redhat-1.jar:2.3.1.Final-redhat-1]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847) [jboss-servlet-api_3.0_spec-1.0.2.Final-redhat-2.jar:1.0.2.Final-redhat-2]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:295) [jbossweb-7.5.19.Final-redhat-1.jar:7.5.19.Final-redhat-1]
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:214) [jbossweb-7.5.19.Final-redhat-1.jar:7.5.19.Final-redhat-1]
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:231) [jbossweb-7.5.19.Final-redhat-1.jar:7.5.19.Final-redhat-1]
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:149) [jbossweb-7.5.19.Final-redhat-1.jar:7.5.19.Final-redhat-1]
	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:512) [jbossweb-7.5.19.Final-redhat-1.jar:7.5.19.Final-redhat-1]
	at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:169) [jboss-as-web-7.5.12.Final-redhat-1.jar:7.5.12.Final-redhat-1]
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:150) [jbossweb-7.5.19.Final-redhat-1.jar:7.5.19.Final-redhat-1]
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:97) [jbossweb-7.5.19.Final-redhat-1.jar:7.5.19.Final-redhat-1]
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:102) [jbossweb-7.5.19.Final-redhat-1.jar:7.5.19.Final-redhat-1]
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:344) [jbossweb-7.5.19.Final-redhat-1.jar:7.5.19.Final-redhat-1]
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:854) [jbossweb-7.5.19.Final-redhat-1.jar:7.5.19.Final-redhat-1]
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:656) [jbossweb-7.5.19.Final-redhat-1.jar:7.5.19.Final-redhat-1]
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:926) [jbossweb-7.5.19.Final-redhat-1.jar:7.5.19.Final-redhat-1]
	at java.lang.Thread.run(Thread.java:745) [rt.jar:1.8.0_102]
Caused by: com.arjuna.wst.SystemException: java.lang.NullPointerException
	at com.arjuna.mwlabs.wst11.at.remote.TransactionManagerImple.registerParticipant(TransactionManagerImple.java:177) [jbossxts-4.17.38.Final-redhat-1.jar:4.17.38.Final-redhat-1]
	at com.arjuna.mwlabs.wst11.at.remote.TransactionManagerImple.enlistForDurableTwoPhase(TransactionManagerImple.java:41) [jbossxts-4.17.38.Final-redhat-1.jar:4.17.38.Final-redhat-1]
	at org.jboss.as.quickstarts.wsat.simple.RestaurantServiceATImpl.makeBooking(RestaurantServiceATImpl.java:74) [classes:]
	... 58 more

      The goal of this enhancement is to provide such logging on places where exception stacktrace is currently thrown away (not written to log).

              Unassigned Unassigned
              ochaloup@redhat.com Ondrej Chaloupka (Inactive)
              Votes:
              0 Vote for this issue
              Watchers:
              1 Start watching this issue

                Created:
                Updated: