[
https://issues.apache.org/jira/browse/CXF-7712?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Muni updated CXF-7712:
----------------------
Priority: Major (was: Minor)
> LoggingInInterceptor
> --------------------
>
> Key: CXF-7712
> URL: https://issues.apache.org/jira/browse/CXF-7712
> Project: CXF
> Issue Type: Bug
> Components: JAX-WS Runtime
> Affects Versions: 3.1.1
> Reporter: Muni
> Priority: Major
> Attachments: CXF2-Objects.JPG, CXF3-Objects.JPG
>
>
> I am not a pro working with CXF, but I can confidently say that there is
> some help required.
> Issue:
> After we migrated to CXF3.1.0 from 2.7.4 (along with Java8), we started
> observing that the payload response entity is not being read correctly and
> sent back calling service. Instead it's being sent back as
> java.io.SequenceInputStream (in case of errors >= 400) as per code exist in
> ClientProxyImpl.java CXF class.
> In case of success responses we have specific check exists (i.e.,
> checkresponse() method for errors status >= 400) and if it is < 400, we get
> thru the readBody() method and read the response entity contents to send back
> the response. But in case of response codes >= 400, we don't have any
> associated response entity reading in current CXF 3.1.0 code to pick the
> payload correctly (this is happening because we now have
> LoggingInInterceptor->logInputSream() method doing bis=new
> SequenceInputStream(.....), which now have an object structure on Java8.
> In older versions we had bis=new InputStream(bos) in LoggingInInterceptor,
> when calling writingPayLoad(), so it was straightforward to get the response
> payload from ResponseImpl object contents (ref to my screenshots).
> The reason I request someone to help on this, because we process the detailed
> error message we get in case of 400 responses.
>
> *Logs:*
> 2018-04-16 09:34:33,697 DEBUG [tomcat-http--6] {}
> org.springframework.integration.channel.DirectChannel - preSend on channel
> 'authorizeUserResponseRouter', message: GenericMessage
> [{color:#FF0000}*payload=java.io.SequenceInputStream@2dd8abaf*{color},
> headers=
> {responseRootElement=authorizeUsersResponse,
> replyChannel=org.springframework.messaging.core.GenericMessagingTemplate$TemporaryReplyChannel@7f2b737a,
>
> errorChannel=org.springframework.messaging.core.GenericMessagingTemplate$TemporaryReplyChannel@7f2b737a,
> id=dd33d939-0daa-0bc6-c578-d34aece33c4a,
> MESSAGE_INFORMATION_HOLDER=javax.xml.ws.Holder@68c33562,
> timestamp=1523889273697}
> ]
> -------------------------------------
> ID: 5
> Response-Code: 400
> Encoding: ISO-8859-1
> Content-Type: application/xml
> Headers:
> {connection=[close], content-type=[application/xml], Date=[Mon, 16 Apr 2018
> 14:34:33 GMT], exception=[User not eligible], Server=[Apache-Coyote/1.1],
> transfer-encoding=[chunked],
> x-security-app-name=[clientapp-rest:1.0.41-1599],
> x-security-correlation-id=[cccc4e4e-f7f3-4ba4-9b72-4d32538ecf02],
> x-security-host=[server001],
> x-security-msg-id=[bacb9976-ac58-4546-9379-1332e2530d00],
> x-security-msg-version=[1], x-security-response-code=[200],
> x-security-service-version=[1.0.41-1599],
> x-security-timestamp=[XXXXXXXXX3652]}
> Payload: <?xml version="1.0" encoding="UTF-8"
> standalone="yes"?><ErrorResponse xmlns="namespacev1"><title>Bad
> Request</title><code>422</code><message>User not
> eligible</message></ErrorResponse>
> --------------------------------------
> 2018-04-16 09:34:33,662 DEBUG [tomcat-http--6] {}
> org.apache.cxf.phase.PhaseInterceptorChain - Invoking handleMessage on
> interceptor
> myapp.client.security.client.penta.HttpHeaderInInterceptor@696f1a06
> 2018-04-16 09:34:33,666 DEBUG [tomcat-http--6] {}
> org.apache.cxf.phase.PhaseInterceptorChain - Invoking handleMessage on
> interceptor
> org.apache.cxf.jaxrs.client.spec.ClientResponseFilterInterceptor@452f892b
> 2018-04-16 09:34:33,681 DEBUG [tomcat-http--6] {}
> org.apache.cxf.phase.PhaseInterceptorChain - Adding interceptor
> org.apache.cxf.ws.policy.ClientPolicyInFaultInterceptor@3201c39f to phase
> receive
> 2018-04-16 09:34:33,681 DEBUG [tomcat-http--6] {}
> org.apache.cxf.phase.PhaseInterceptorChain - Adding interceptor
> org.apache.cxf.ws.policy.PolicyVerificationInFaultInterceptor@925feb4 to
> phase pre-invoke
> 2018-04-16 09:34:33,681 DEBUG [tomcat-http--6] {}
> org.apache.cxf.phase.PhaseInterceptorChain - Adding interceptor
> org.apache.cxf.interceptor.LoggingInInterceptor@f56e35b to phase receive
> 2018-04-16 09:34:33,681 DEBUG [tomcat-http--6] {}
> org.apache.cxf.phase.PhaseInterceptorChain - Adding interceptor
> org.apache.cxf.interceptor.ClientFaultConverter@38b4d45b to phase unmarshal
> 2018-04-16 09:34:33,681 DEBUG [tomcat-http--6] {}
> org.apache.cxf.phase.PhaseInterceptorChain - Chain
> org.apache.cxf.phase.PhaseInterceptorChain@1cba32cd was created. Current flow:
> receive [ClientPolicyInFaultInterceptor, LoggingInInterceptor]
> unmarshal [ClientFaultConverter]
> pre-invoke [PolicyVerificationInFaultInterceptor]
> 2018-04-16 09:34:33,681 DEBUG [tomcat-http--6] {}
> org.apache.cxf.phase.PhaseInterceptorChain - Invoking handleMessage on
> interceptor org.apache.cxf.ws.policy.ClientPolicyInFaultInterceptor@3201c39f
> 2018-04-16 09:34:33,682 DEBUG [tomcat-http--6] {}
> org.apache.cxf.ws.policy.ClientPolicyInFaultInterceptor - conduit: conduit:
> class org.apache.cxf.transport.http.URLConnectionHTTPConduit538646526target:
> [https://server001.myapp.com/v1/AuthorizationService/entitlements/authorizations]
> 2018-04-16 09:34:33,682 DEBUG [tomcat-http--6] {}
> org.apache.cxf.ws.policy.ClientPolicyInFaultInterceptor - ep:
> org.apache.cxf.ws.policy.EndpointPolicyImpl@6ed8d17
> 2018-04-16 09:34:33,682 DEBUG [tomcat-http--6] {}
> org.apache.cxf.ws.policy.ClientPolicyInFaultInterceptor - faultInterceptors:
> []
> 2018-04-16 09:34:33,682 DEBUG [tomcat-http--6] {}
> org.apache.cxf.phase.PhaseInterceptorChain - Invoking handleMessage on
> interceptor org.apache.cxf.interceptor.LoggingInInterceptor@f56e35b
> 2018-04-16 09:34:33,682 INFO [tomcat-http--6] {}
> org.apache.cxf.interceptor.LoggingInInterceptor - Inbound Message
> ----------------------------
> ID: 5
> Address:
> [https://server001.myapp.com/v1/AuthorizationService/entitlements/authorizations]
> Http-Method: POST
> Content-Type: application/xml
> Headers:
> {Accept=[application/xml], Context-Type=[application/xml],
> Content-Type=[application/xml],
> x-security-header-signature=[QliBhrMwTdlczMysN/OEBFDUXHZgvGzl9VvvK4OOaloEtmMVxSbRcNAx1mydZJ4kT0/sFUh/dkZlbtzLRLaPLgPvuSAy7Kve0fnwYMdW4ad86l3KwUbH46nLJi3GCKm0anT+tmb68HXXqLRw4Ph6sy4Lcwgvo5OY+7u36X0dckw=],
> x-security-msg-version=[1], x-security-host=[SY4-67HSN22],
> x-security-msg-id=[bacb9976-ac58-4546-9379-1332e2530d00],
> x-security-app-name=[ebs-non-revenue-gateway:3.2.4-SNAPSHOT-SNAPSHOT],
> x-security-payload-signature=[MPubxRTbdTzdRUu96hKMyc+uHVUHMxHRQBWXkXcj6jXkbqae0m23IgaF6VanzHYd4HpV4n/WyhqbVnnTBW/Eo+b6GhGbTdtjl0+r9gNoofjCgZMUQkj6Wj3kE3fyfsYAyr5i2Ae2YUYuV46PWbXjWY5H+hN/cJVghZQaQm5Hv+8=],
> x-security-correlation-id=[cccc4e4e-f7f3-4ba4-9b72-4d32538ecf02],
> x-security-service-version=[1.0.0], x-security-timestamp=[1523889273497],
> x-security-security-token=[249d0ce2-73cf-409f-a295-e298c0ba92c2:amcyY2JzdGQ:eGxkdGZzMTA:LmRldi4:3]}
> --------------------------------------
> 2018-04-16 09:34:33,682 DEBUG [tomcat-http--6] {}
> org.apache.cxf.phase.PhaseInterceptorChain - Invoking handleMessage on
> interceptor org.apache.cxf.interceptor.ClientFaultConverter@38b4d45b
> 2018-04-16 09:34:33,682 DEBUG [tomcat-http--6] {}
> org.apache.cxf.phase.PhaseInterceptorChain - Invoking handleMessage on
> interceptor
> org.apache.cxf.ws.policy.PolicyVerificationInFaultInterceptor@925feb4
> 2018-04-16 09:34:33,684 INFO [tomcat-http--6] {}
> myapp.client.security.client.penta.SecurityInvocationHandler - Error Code:400
> Error Message:java.io.SequenceInputStream@2dd8abaf
> 2018-04-16 09:34:33,684 DEBUG [tomcat-http--6] {}
> myapp.core.service.authorize.ServiceOutboundGatewayImpl - Authorize Service
> errorjava.io.SequenceInputStream@2dd8abaf
> javax.ws.rs.BadRequestException: HTTP 400 Bad Request
> at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
> at
> sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
> at
> sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
>
>
> *NOTE:*
> # **There is no problem with Interceptors being invoked, everything looks
> good on that part.
> # Only difference is, in case of success we have code that reads the
> response body (entity) by checking response code (not >= 400).
> # In case of an error response which has response-code >=400, are being
> treated as faults and the respective interceptors are being invoked
> correctly. But the issue is earlier version had directly mapping complete
> response in message.getContents(), but now that's no longer working as this
> code in CXF classes gets me response
> payload=java.io.SequenceInputStream@2dd8abaf, which is causing a problem with
> my response handling, as I was totally dependent on CXF response body in case
> of these 400 error responses.
--
This message was sent by Atlassian JIRA
(v7.6.3#76005)
