Return-Path: X-Original-To: apmail-camel-dev-archive@www.apache.org Delivered-To: apmail-camel-dev-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id BD7EAEFC4 for ; Sun, 6 Jan 2013 17:33:28 +0000 (UTC) Received: (qmail 52686 invoked by uid 500); 6 Jan 2013 17:33:28 -0000 Delivered-To: apmail-camel-dev-archive@camel.apache.org Received: (qmail 52622 invoked by uid 500); 6 Jan 2013 17:33:28 -0000 Mailing-List: contact dev-help@camel.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@camel.apache.org Delivered-To: mailing list dev@camel.apache.org Received: (qmail 52600 invoked by uid 99); 6 Jan 2013 17:33:28 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Sun, 06 Jan 2013 17:33:28 +0000 X-ASF-Spam-Status: No, hits=1.5 required=5.0 tests=HTML_MESSAGE,RCVD_IN_DNSWL_LOW,SPF_PASS X-Spam-Check-By: apache.org Received-SPF: pass (athena.apache.org: domain of christian.mueller@gmail.com designates 209.85.219.43 as permitted sender) Received: from [209.85.219.43] (HELO mail-oa0-f43.google.com) (209.85.219.43) by apache.org (qpsmtpd/0.29) with ESMTP; Sun, 06 Jan 2013 17:33:23 +0000 Received: by mail-oa0-f43.google.com with SMTP id k1so16844758oag.30 for ; Sun, 06 Jan 2013 09:33:03 -0800 (PST) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=20120113; h=mime-version:date:message-id:subject:from:to:content-type; bh=mgTpUAaEy0pANYGkdhhyJGHReD095+3/Yjv/AtHIZhU=; b=nIT7ogbgts8bWORu+tYxli33FoE1Q5nLLGdHwfr0G3dPFQ7vAqPxqzAT/LF6RxeK02 TjKth7KhcE+iIF5beRUBBdYssA7LlVe8UVscgb9YW+3OndMLMsIrltkL2e8w+E11xh2X t91m9qkeGNZr91qnLtK43fyNx8qHlxdfAGwOaKq8gqETYES0/Z3vX+I/ZMeexb5JO9MD wPiVSRfMhEgSwklI06lODG4XAJCjyqe+JLOueSJCV1oVndjuEeXmI5B0tcUMlLaadIYR Xu3uI6rY8lKqIZrrO3aqtKGJQmEHzKvqnRiYwokMSfSMSUnv5U7WF2ziYM0aUz0wTYRV aQmg== MIME-Version: 1.0 Received: by 10.60.1.73 with SMTP id 9mr31756090oek.130.1357493582825; Sun, 06 Jan 2013 09:33:02 -0800 (PST) Received: by 10.182.114.103 with HTTP; Sun, 6 Jan 2013 09:33:02 -0800 (PST) Date: Sun, 6 Jan 2013 18:33:02 +0100 Message-ID: Subject: Disturbing WARNING from CXF From: =?ISO-8859-1?Q?Christian_M=FCller?= To: dev@cxf.apache.org, dev@servicemix.apache.org, dev@camel.apache.org Content-Type: multipart/alternative; boundary=e89a8fb20660ca07a504d2a21aad X-Virus-Checked: Checked by ClamAV on apache.org --e89a8fb20660ca07a504d2a21aad Content-Type: text/plain; charset=ISO-8859-1 Environment: SMX 4.5.0-SNAPSHOT (20121127) + CXF 2.6.3 + Camel 2.10.3 For the upcoming ESB Performance Test Round 7 [1], I prepared the test for the new ServiceMix 4.5.0 and Camel 2.10.3. You can find the complete project at [2]. The README file [3] explains how I "tuned" my installation. All tests run successfully. However, in my log file, I see lots of stack traces like the one below. Because most of the tests are also slower compared with ServiceMix 4.4.2 + Camel 2.8.5 + CXF 2.4.6 (compare [4] with [5]), I'm wondering whether this is the reason? Is this a known (CXF, Jetty, ...) issue? I will check if I see this also with CXF 2.6.4. [1] http://esbperformance.org/display/comparison/Framework+-+Performance+Test+Cases [2] https://github.com/muellerc/esbperformance/tree/master/servicemix-osgi [3] https://github.com/muellerc/esbperformance/blob/master/servicemix-osgi/README.txt [4] https://github.com/muellerc/esbperformance/blob/esbperformance-1.1.x/servicemix-osgi/RESULTS-local-execution.txt [5] https://github.com/muellerc/esbperformance/blob/master/servicemix-osgi/RESULTS-local-execution.txt Stacktrace: 17:39:21,664 | WARN | tp1949086865-105 | PhaseInterceptorChain | 75 - org.apache.cxf.cxf-api - 2.6.3 | Interceptor for { http://services.samples/xsd}CBRProxy has thrown exception, unwinding now org.apache.cxf.interceptor.Fault: stream is closed at org.apache.camel.component.cxf.interceptors.RawMessageContentRedirectInterceptor.handleMessage(RawMessageContentRedirectInterceptor.java:59)[153:org.apache.camel.camel-cxf:2.10.3] at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:262)[75:org.apache.cxf.cxf-api:2.6.3] at org.apache.cxf.interceptor.OutgoingChainInterceptor.handleMessage(OutgoingChainInterceptor.java:77)[75:org.apache.cxf.cxf-api:2.6.3] at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:262)[75:org.apache.cxf.cxf-api:2.6.3] at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:121)[75:org.apache.cxf.cxf-api:2.6.3] at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDestination.java:236)[105:org.apache.cxf.cxf-rt-transports-http:2.6.3] at org.apache.cxf.transport.servlet.ServletController.invokeDestination(ServletController.java:213)[105:org.apache.cxf.cxf-rt-transports-http:2.6.3] at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:193)[105:org.apache.cxf.cxf-rt-transports-http:2.6.3] at org.apache.cxf.transport.servlet.CXFNonSpringServlet.invoke(CXFNonSpringServlet.java:130)[105:org.apache.cxf.cxf-rt-transports-http:2.6.3] at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest(AbstractHTTPServlet.java:221)[105:org.apache.cxf.cxf-rt-transports-http:2.6.3] at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doPost(AbstractHTTPServlet.java:141)[105:org.apache.cxf.cxf-rt-transports-http:2.6.3] at javax.servlet.http.HttpServlet.service(HttpServlet.java:713)[98:org.apache.geronimo.specs.geronimo-servlet_2.5_spec:1.1.2] at org.apache.cxf.transport.servlet.AbstractHTTPServlet.service(AbstractHTTPServlet.java:197)[105:org.apache.cxf.cxf-rt-transports-http:2.6.3] at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:547)[87:org.eclipse.jetty.servlet:7.5.4.v20111024] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:480)[87:org.eclipse.jetty.servlet:7.5.4.v20111024] at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:70)[103:org.ops4j.pax.web.pax-web-jetty:1.0.11] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:119)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:520)[86:org.eclipse.jetty.security:7.5.4.v20111024] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:227)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:941)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:117)[103:org.ops4j.pax.web.pax-web-jetty:1.0.11] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:409)[87:org.eclipse.jetty.servlet:7.5.4.v20111024] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:186)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:875)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:117)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:149)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:110)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.Server.handleAsync(Server.java:394)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.HttpConnection.handleRequest(HttpConnection.java:446)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:48)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:586)[85:org.eclipse.jetty.io:7 .5.4.v20111024] at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:44)[85:org.eclipse.jetty.io:7 .5.4.v20111024] at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:598)[93:org.eclipse.jetty.util:7.5.4.v20111024] at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:533)[93:org.eclipse.jetty.util:7.5.4.v20111024] at java.lang.Thread.run(Thread.java:680)[:1.6.0_37] Caused by: java.io.IOException: stream is closed at sun.net.www.http.ChunkedInputStream.ensureOpen(ChunkedInputStream.java:151)[:1.6.0_37] at sun.net.www.http.ChunkedInputStream.available(ChunkedInputStream.java:691)[:1.6.0_37] at java.io.FilterInputStream.available(FilterInputStream.java:142)[:1.6.0_37] at org.apache.cxf.helpers.IOUtils.copy(IOUtils.java:126)[75:org.apache.cxf.cxf-api:2.6.3] at org.apache.cxf.helpers.IOUtils.copy(IOUtils.java:103)[75:org.apache.cxf.cxf-api:2.6.3] at org.apache.camel.component.cxf.interceptors.RawMessageContentRedirectInterceptor.handleMessage(RawMessageContentRedirectInterceptor.java:56)[153:org.apache.camel.camel-cxf:2.10.3] ... 34 more 17:39:21,666 | WARN | tp1949086865-105 | PhaseInterceptorChain | 75 - org.apache.cxf.cxf-api - 2.6.3 | Interceptor for { http://services.samples/xsd}CBRProxy has thrown exception, unwinding now org.apache.cxf.binding.soap.SoapFault: Error writing to XMLStreamWriter. at org.apache.cxf.binding.soap.interceptor.SoapOutInterceptor$SoapOutEndingInterceptor.handleMessage(SoapOutInterceptor.java:288)[82:org.apache.cxf.cxf-rt-bindings-soap:2.6.3] at org.apache.cxf.binding.soap.interceptor.SoapOutInterceptor$SoapOutEndingInterceptor.handleMessage(SoapOutInterceptor.java:270)[82:org.apache.cxf.cxf-rt-bindings-soap:2.6.3] at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:262)[75:org.apache.cxf.cxf-api:2.6.3] at org.apache.cxf.interceptor.AbstractFaultChainInitiatorObserver.onMessage(AbstractFaultChainInitiatorObserver.java:112)[75:org.apache.cxf.cxf-api:2.6.3] at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:322)[75:org.apache.cxf.cxf-api:2.6.3] at org.apache.cxf.interceptor.OutgoingChainInterceptor.handleMessage(OutgoingChainInterceptor.java:77)[75:org.apache.cxf.cxf-api:2.6.3] at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:262)[75:org.apache.cxf.cxf-api:2.6.3] at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:121)[75:org.apache.cxf.cxf-api:2.6.3] at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDestination.java:236)[105:org.apache.cxf.cxf-rt-transports-http:2.6.3] at org.apache.cxf.transport.servlet.ServletController.invokeDestination(ServletController.java:213)[105:org.apache.cxf.cxf-rt-transports-http:2.6.3] at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:193)[105:org.apache.cxf.cxf-rt-transports-http:2.6.3] at org.apache.cxf.transport.servlet.CXFNonSpringServlet.invoke(CXFNonSpringServlet.java:130)[105:org.apache.cxf.cxf-rt-transports-http:2.6.3] at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest(AbstractHTTPServlet.java:221)[105:org.apache.cxf.cxf-rt-transports-http:2.6.3] at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doPost(AbstractHTTPServlet.java:141)[105:org.apache.cxf.cxf-rt-transports-http:2.6.3] at javax.servlet.http.HttpServlet.service(HttpServlet.java:713)[98:org.apache.geronimo.specs.geronimo-servlet_2.5_spec:1.1.2] at org.apache.cxf.transport.servlet.AbstractHTTPServlet.service(AbstractHTTPServlet.java:197)[105:org.apache.cxf.cxf-rt-transports-http:2.6.3] at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:547)[87:org.eclipse.jetty.servlet:7.5.4.v20111024] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:480)[87:org.eclipse.jetty.servlet:7.5.4.v20111024] at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:70)[103:org.ops4j.pax.web.pax-web-jetty:1.0.11] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:119)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:520)[86:org.eclipse.jetty.security:7.5.4.v20111024] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:227)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:941)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:117)[103:org.ops4j.pax.web.pax-web-jetty:1.0.11] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:409)[87:org.eclipse.jetty.servlet:7.5.4.v20111024] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:186)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:875)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:117)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:149)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:110)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.Server.handleAsync(Server.java:394)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.HttpConnection.handleRequest(HttpConnection.java:446)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:48)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:586)[85:org.eclipse.jetty.io:7 .5.4.v20111024] at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:44)[85:org.eclipse.jetty.io:7 .5.4.v20111024] at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:598)[93:org.eclipse.jetty.util:7.5.4.v20111024] at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:533)[93:org.eclipse.jetty.util:7.5.4.v20111024] at java.lang.Thread.run(Thread.java:680)[:1.6.0_37] Caused by: com.ctc.wstx.exc.WstxIOException: Closed at com.ctc.wstx.sw.BaseStreamWriter._finishDocument(BaseStreamWriter.java:1406)[66:woodstox-core-asl:4.1.4] at com.ctc.wstx.sw.BaseStreamWriter.writeEndDocument(BaseStreamWriter.java:536)[66:woodstox-core-asl:4.1.4] at org.apache.cxf.binding.soap.interceptor.SoapOutInterceptor$SoapOutEndingInterceptor.handleMessage(SoapOutInterceptor.java:282)[82:org.apache.cxf.cxf-rt-bindings-soap:2.6.3] ... 37 more Caused by: java.io.IOException: Closed at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:152)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:101)[89:org.eclipse.jetty.server:7.5.4.v20111024] at org.apache.cxf.io.AbstractWrappedOutputStream.write(AbstractWrappedOutputStream.java:51)[75:org.apache.cxf.cxf-api:2.6.3] at com.ctc.wstx.sw.EncodingXmlWriter.flushBuffer(EncodingXmlWriter.java:697)[66:woodstox-core-asl:4.1.4] at com.ctc.wstx.sw.EncodingXmlWriter.flush(EncodingXmlWriter.java:171)[66:woodstox-core-asl:4.1.4] at com.ctc.wstx.sw.EncodingXmlWriter.close(EncodingXmlWriter.java:150)[66:woodstox-core-asl:4.1.4] at com.ctc.wstx.sw.BaseStreamWriter._finishDocument(BaseStreamWriter.java:1404)[66:woodstox-core-asl:4.1.4] ... 39 more Best, Christian -- --e89a8fb20660ca07a504d2a21aad--