[
https://issues.apache.org/jira/browse/CXF-5761?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Daniel Kulp updated CXF-5761:
-----------------------------
Fix Version/s: 2.6.15
> InInterceptor throws exception with PrettyLogging and big message size
> ----------------------------------------------------------------------
>
> Key: CXF-5761
> URL: https://issues.apache.org/jira/browse/CXF-5761
> Project: CXF
> Issue Type: Bug
> Components: Core
> Affects Versions: 3.0.0
> Environment: Java 1.7
> Reporter: member sound
> Assignee: Daniel Kulp
> Fix For: 2.6.15, 2.7.12, 3.0.1
>
>
> Since 2.7.11 (and also in 3.0.0) I have problems with pretty print.
> Client client = ClientProxy.getClient(port);
> LoggingInInterceptor loggingInInterceptor = new
> LoggingInInterceptor();
> loggingInInterceptor.setPrettyLogging(true);
> client.getInInterceptors().add(loggingInInterceptor);
> If I enable pretty print, 2.7.10 works as expected. Any version above throws
> an exception. But ONLY if prettylogging on ininterceptor is enabled.
> Disabling it will log the message normally.
> The root cause is in cxf-core.
> I cannot post the entire webservice response as it is not public. Though it
> is obvious that only the version change causes this error. But an excerpt of
> the complaining element is:
> [...]
> <mytag:MyKey>xFuGPYCHgC5vICGiajJE9VJilViIlUaNUmKVWIiVRo1SYpVYiJVGjVJilViIlUaNUmKV
> Here the closing tag is not found because the message probably exceeds the
> maximum allowed length for logging and is truncated.
> There must be a difference in handling this since 2.7.10, as with this old
> version a too long and truncated soap response is not a problem!
> What I noticed: the logging by InInterceptor is missing the "(message
> truncated to X bytes)" statement.
> If I create the interceptor with a bigger threshold, the error is also not
> thrown:
> new LoggingInInterceptor(1000000); //OK as it is bigger than [1,49152] stated
> in the exception
> Or just taking an old implementation also works:
> https://fisheye6.atlassian.com/browse/~br%3Dsplit-spring/cxf/core/src/main/java/org/apache/cxf/interceptor/LoggingInInterceptor.java?u=3&r=eb4e91ab9d3e29cc1ed35f61848a0a7ae3c917b8
> Exception:
> javax.xml.ws.soap.SOAPFaultException: Unexpected EOF; was expecting a close
> tag for element <mytag:MyKey>
> at [row,col {unknown-source}]: [1,49152]
> at
> org.apache.cxf.jaxws.JaxWsClientProxy.invoke(JaxWsClientProxy.java:159)
> ~[cxf-rt-frontend-jaxws-3.0.0.jar:3.0.0]
> at com.sun.proxy.$Proxy141.service(Unknown Source) ~[?:?]
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native
> Method) ~[?:1.7.0_51]
> at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> ~[?:1.7.0_51]
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> ~[?:1.7.0_51]
> at java.lang.reflect.Method.invoke(Method.java:606)
> ~[?:1.7.0_51]
> at
> org.springframework.expression.spel.support.ReflectiveMethodExecutor.execute(ReflectiveMethodExecutor.java:63)
> ~[spring-expression-4.0.5.RELEASE.jar:4.0.5.RELEASE]
> at
> org.springframework.expression.spel.ast.MethodReference.getValueInternal(MethodReference.java:122)
> ~[spring-expression-4.0.5.RELEASE.jar:4.0.5.RELEASE]
> at
> org.springframework.expression.spel.ast.MethodReference.access$000(MethodReference.java:44)
> ~[spring-expression-4.0.5.RELEASE.jar:4.0.5.RELEASE]
> at
> org.springframework.expression.spel.ast.MethodReference$MethodValueRef.getValue(MethodReference.java:258)
> ~[spring-expression-4.0.5.RELEASE.jar:4.0.5.RELEASE]
> at
> org.springframework.expression.spel.ast.CompoundExpression.getValueInternal(CompoundExpression.java:84)
> ~[spring-expression-4.0.5.RELEASE.jar:4.0.5.RELEASE]
> at
> org.springframework.expression.spel.ast.SpelNodeImpl.getTypedValue(SpelNodeImpl.java:114)
> ~[spring-expression-4.0.5.RELEASE.jar:4.0.5.RELEASE]
> at
> org.springframework.expression.spel.standard.SpelExpression.getValue(SpelExpression.java:111)
> ~[spring-expression-4.0.5.RELEASE.jar:4.0.5.RELEASE]
> at
> org.springframework.integration.util.AbstractExpressionEvaluator.evaluateExpression(AbstractExpressionEvaluator.java:159)
> ~[spring-integration-core-4.0.0.RELEASE.jar:?]
> at
> org.springframework.integration.util.MessagingMethodInvokerHelper.processInternal(MessagingMethodInvokerHelper.java:268)
> ~[spring-integration-core-4.0.0.RELEASE.jar:?]
> at
> org.springframework.integration.util.MessagingMethodInvokerHelper.process(MessagingMethodInvokerHelper.java:142)
> ~[spring-integration-core-4.0.0.RELEASE.jar:?]
> at
> org.springframework.integration.handler.MethodInvokingMessageProcessor.processMessage(MethodInvokingMessageProcessor.java:75)
> ~[spring-integration-core-4.0.0.RELEASE.jar:?]
> at
> org.springframework.integration.handler.ServiceActivatingHandler.handleRequestMessage(ServiceActivatingHandler.java:71)
> ~[spring-integration-core-4.0.0.RELEASE.jar:?]
> at
> org.springframework.integration.handler.AbstractReplyProducingMessageHandler.handleMessageInternal(AbstractReplyProducingMessageHandler.java:170)
> ~[spring-integration-core-4.0.0.RELEASE.jar:?]
> at
> org.springframework.integration.handler.AbstractMessageHandler.handleMessage(AbstractMessageHandler.java:78)
> ~[spring-integration-core-4.0.0.RELEASE.jar:?]
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native
> Method) ~[?:1.7.0_51]
> at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> ~[?:1.7.0_51]
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> ~[?:1.7.0_51]
> at java.lang.reflect.Method.invoke(Method.java:606)
> ~[?:1.7.0_51]
> at
> org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
> ~[spring-aop-4.0.5.RELEASE.jar:4.0.5.RELEASE]
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:190)
> ~[spring-aop-4.0.5.RELEASE.jar:4.0.5.RELEASE]
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:157)
> ~[spring-aop-4.0.5.RELEASE.jar:4.0.5.RELEASE]
> at
> org.springframework.integration.monitor.SimpleMessageHandlerMetrics.handleMessage(SimpleMessageHandlerMetrics.java:106)
> ~[spring-integration-jmx-4.0.0.RELEASE.jar:?]
> at
> org.springframework.integration.monitor.SimpleMessageHandlerMetrics.invoke(SimpleMessageHandlerMetrics.java:86)
> ~[spring-integration-jmx-4.0.0.RELEASE.jar:?]
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179)
> ~[spring-aop-4.0.5.RELEASE.jar:4.0.5.RELEASE]
> at
> org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:207)
> ~[spring-aop-4.0.5.RELEASE.jar:4.0.5.RELEASE]
> at com.sun.proxy.$Proxy93.handleMessage(Unknown Source) ~[?:?]
> at
> org.springframework.integration.dispatcher.AbstractDispatcher.tryOptimizedDispatch(AbstractDispatcher.java:116)
> ~[spring-integration-core-4.0.0.RELEASE.jar:?]
> at
> org.springframework.integration.dispatcher.UnicastingDispatcher.doDispatch(UnicastingDispatcher.java:101)
> ~[spring-integration-core-4.0.0.RELEASE.jar:?]
> at
> org.springframework.integration.dispatcher.UnicastingDispatcher.dispatch(UnicastingDispatcher.java:97)
> ~[spring-integration-core-4.0.0.RELEASE.jar:?]
> at
> org.springframework.integration.channel.AbstractSubscribableChannel.doSend(AbstractSubscribableChannel.java:77)
> ~[spring-integration-core-4.0.0.RELEASE.jar:?]
> at
> org.springframework.integration.channel.AbstractMessageChannel.send(AbstractMessageChannel.java:255)
> ~[spring-integration-core-4.0.0.RELEASE.jar:?]
> at
> org.springframework.messaging.core.GenericMessagingTemplate.doSend(GenericMessagingTemplate.java:114)
> [spring-messaging-4.0.3.RELEASE.jar:4.0.3.RELEASE]
> at
> org.springframework.messaging.core.GenericMessagingTemplate.doSendAndReceive(GenericMessagingTemplate.java:154)
> [spring-messaging-4.0.3.RELEASE.jar:4.0.3.RELEASE]
> at
> org.springframework.messaging.core.GenericMessagingTemplate.doSendAndReceive(GenericMessagingTemplate.java:44)
> [spring-messaging-4.0.3.RELEASE.jar:4.0.3.RELEASE]
> at
> org.springframework.messaging.core.AbstractMessagingTemplate.sendAndReceive(AbstractMessagingTemplate.java:75)
> [spring-messaging-4.0.3.RELEASE.jar:4.0.3.RELEASE]
> at
> org.springframework.integration.gateway.MessagingGatewaySupport.doSendAndReceive(MessagingGatewaySupport.java:250)
> [spring-integration-core-4.0.0.RELEASE.jar:?]
> at
> org.springframework.integration.gateway.MessagingGatewaySupport.sendAndReceiveMessage(MessagingGatewaySupport.java:224)
> [spring-integration-core-4.0.0.RELEASE.jar:?]
> at
> org.springframework.integration.ip.tcp.TcpInboundGateway.doOnMessage(TcpInboundGateway.java:99)
> [spring-integration-ip-4.0.0.RELEASE.jar:?]
> at
> org.springframework.integration.ip.tcp.TcpInboundGateway.onMessage(TcpInboundGateway.java:89)
> [spring-integration-ip-4.0.0.RELEASE.jar:?]
> at
> org.springframework.integration.ip.tcp.connection.TcpNioConnection.sendToChannel(TcpNioConnection.java:293)
> [spring-integration-ip-4.0.0.RELEASE.jar:?]
> at
> org.springframework.integration.ip.tcp.connection.TcpNioConnection.run(TcpNioConnection.java:206)
> [spring-integration-ip-4.0.0.RELEASE.jar:?]
> at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
> [?:1.7.0_51]
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
> [?:1.7.0_51]
> at java.lang.Thread.run(Thread.java:744) [?:1.7.0_51]
> Caused by: com.ctc.wstx.exc.WstxEOFException: Unexpected EOF; was expecting a
> close tag for element <mytag:MyKey>
> at [row,col {unknown-source}]: [1,49152]
> at
> com.ctc.wstx.sr.StreamScanner.throwUnexpectedEOF(StreamScanner.java:685)
> ~[woodstox-core-asl-4.3.0.jar:4.3.0]
> at
> com.ctc.wstx.sr.BasicStreamReader.throwUnexpectedEOF(BasicStreamReader.java:5515)
> ~[woodstox-core-asl-4.3.0.jar:4.3.0]
> at
> com.ctc.wstx.sr.BasicStreamReader.nextFromTree(BasicStreamReader.java:2718)
> ~[woodstox-core-asl-4.3.0.jar:4.3.0]
> at
> com.ctc.wstx.sr.BasicStreamReader.next(BasicStreamReader.java:1069)
> ~[woodstox-core-asl-4.3.0.jar:4.3.0]
> at
> org.apache.cxf.staxutils.StaxUtils.copy(StaxUtils.java:766)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.staxutils.StaxUtils.copy(StaxUtils.java:696)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.staxutils.StaxUtils.copy(StaxUtils.java:620)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.interceptor.AbstractLoggingInterceptor.writePayload(AbstractLoggingInterceptor.java:163)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.interceptor.LoggingInInterceptor.logInputStream(LoggingInInterceptor.java:213)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.interceptor.LoggingInInterceptor.logging(LoggingInInterceptor.java:151)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.interceptor.LoggingInInterceptor.handleMessage(LoggingInInterceptor.java:80)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:307)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.endpoint.ClientImpl.onMessage(ClientImpl.java:798)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.handleResponseInternal(HTTPConduit.java:1636)
> ~[cxf-rt-transports-http-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.handleResponse(HTTPConduit.java:1525)
> ~[cxf-rt-transports-http-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.close(HTTPConduit.java:1330)
> ~[cxf-rt-transports-http-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.io.CacheAndWriteOutputStream.postClose(CacheAndWriteOutputStream.java:56)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.io.CachedOutputStream.close(CachedOutputStream.java:215)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.transport.AbstractConduit.close(AbstractConduit.java:56)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.transport.http.HTTPConduit.close(HTTPConduit.java:638)
> ~[cxf-rt-transports-http-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.interceptor.MessageSenderInterceptor$MessageSenderEndingInterceptor.handleMessage(MessageSenderInterceptor.java:62)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:307)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.endpoint.ClientImpl.doInvoke(ClientImpl.java:514)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:423)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:326)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:279)
> ~[cxf-core-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.frontend.ClientProxy.invokeSync(ClientProxy.java:96)
> ~[cxf-rt-frontend-simple-3.0.0.jar:3.0.0]
> at
> org.apache.cxf.jaxws.JaxWsClientProxy.invoke(JaxWsClientProxy.java:137)
> ~[cxf-rt-frontend-jaxws-3.0.0.jar:3.0.0]
> ... 57 more
--
This message was sent by Atlassian JIRA
(v6.2#6252)
