[Carbon-jira] [jira] Created: (CARBON-5968) ESB - Caching does not work with message relay turned on
Evanthika Amarasiri (JIRA)
jira at wso2.org
Wed Jan 27 04:37:47 PST 2010
ESB - Caching does not work with message relay turned on
--------------------------------------------------------
Key: CARBON-5968
URL: https://wso2.org/jira/browse/CARBON-5968
Project: WSO2 Carbon
Issue Type: Bug
Components: Caching
Affects Versions: 2.0.3
Environment: Windows XP, JDK1.6
Reporter: Evanthika Amarasiri
Assignee: Supun Kamburugamuva
Caching does not work when message relay option is turned on. The exception thrown when the message is read from the cache is as below
[2010-01-27 17:31:04,781] DEBUG - SynapseMessageReceiver Synapse received a new message for message mediation...
[2010-01-27 17:31:04,781] DEBUG - SynapseMessageReceiver Received To: /
[2010-01-27 17:31:04,781] DEBUG - SynapseMessageReceiver SOAPAction: urn:getQuote
[2010-01-27 17:31:04,781] DEBUG - SynapseMessageReceiver WSA-Action: urn:getQuote
[2010-01-27 17:31:04,781] DEBUG - Axis2SynapseEnvironment Injecting MessageContext
[2010-01-27 17:31:04,781] DEBUG - Axis2SynapseEnvironment Using Main Sequence for injected message
[2010-01-27 17:31:04,781] DEBUG - SequenceMediator Start : Sequence <main>
[2010-01-27 17:31:04,781] DEBUG - SequenceMediator Sequence <SequenceMediator> :: mediate()
[2010-01-27 17:31:04,781] DEBUG - SequenceMediator Start : Sequence key=<test>
[2010-01-27 17:31:04,781] DEBUG - SequenceMediator Executing with key test
[2010-01-27 17:31:04,781] DEBUG - SequenceMediator Start : Sequence <test>
[2010-01-27 17:31:04,781] DEBUG - SequenceMediator Sequence <SequenceMediator> :: mediate()
[2010-01-27 17:31:04,781] DEBUG - InMediator Start : In mediator
[2010-01-27 17:31:04,781] DEBUG - InMediator Current message is incoming - executing child mediators
[2010-01-27 17:31:04,812] DEBUG - InMediator Sequence <InMediator> :: mediate()
[2010-01-27 17:31:04,812] DEBUG - CacheMediator Start : Cache mediator
[2010-01-27 17:31:04,859] DEBUG - CacheMediator Looking up cache at scope : per-host with ID : cacheManager
[2010-01-27 17:31:04,859] DEBUG - CacheMediator Generated request hash : 42674935510861-55-57-108189-1229414-114
[2010-01-27 17:31:04,890] DEBUG - CacheMediator Cache-hit for message ID : urn:uuid:1627CD98AFDDCF4EE51264593666235
[2010-01-27 17:31:04,890] DEBUG - CacheMediator Request message urn:uuid:1627CD98AFDDCF4EE51264593666235 was served from the cache : cacheManager
[2010-01-27 17:31:04,921] ERROR - HttpCoreNIOSender General Error sending response message to : 127.0.0.1
java.lang.RuntimeException: ContentID is null
at org.apache.axiom.om.impl.llom.OMTextImpl.getDataHandler(OMTextImpl.java:363)
at org.wso2.carbon.relay.ExpandingMessageFormatter.findAndWrite2OutputStream(ExpandingMessageFormatter.java:133)
at org.wso2.carbon.relay.ExpandingMessageFormatter.writeTo(ExpandingMessageFormatter.java:78)
at org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncResponse(HttpCoreNIOSender.java:476)
at org.apache.synapse.transport.nhttp.HttpCoreNIOSender.invoke(HttpCoreNIOSender.java:293)
at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:440)
at org.apache.synapse.core.axis2.Axis2Sender.sendBack(Axis2Sender.java:138)
at org.apache.synapse.mediators.builtin.CacheMediator.processRequestMessage(CacheMediator.java:328)
at org.apache.synapse.mediators.builtin.CacheMediator.mediate(CacheMediator.java:156)
at org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:60)
at org.apache.synapse.mediators.filters.InMediator.mediate(InMediator.java:55)
at org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:60)
at org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.java:115)
at org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.java:162)
at org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:60)
at org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.java:115)
at org.apache.synapse.core.axis2.Axis2SynapseEnvironment.injectMessage(Axis2SynapseEnvironment.java:131)
at org.apache.synapse.core.axis2.SynapseMessageReceiver.receive(SynapseMessageReceiver.java:86)
at org.apache.axis2.engine.AxisEngine.receive(AxisEngine.java:178)
at org.apache.axis2.transport.http.HTTPTransportUtils.processHTTPPostRequest(HTTPTransportUtils.java:167)
at org.apache.synapse.transport.nhttp.ServerWorker.processEntityEnclosingMethod(ServerWorker.java:349)
at org.apache.synapse.transport.nhttp.ServerWorker.run(ServerWorker.java:239)
at org.apache.axis2.transport.base.threads.NativeWorkerPool$1.run(NativeWorkerPool.java:58)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:619)
[2010-01-27 17:31:05,406] ERROR - Axis2Sender Unexpected error sending message back
org.apache.axis2.AxisFault: General Error sending response message to : 127.0.0.1
at org.apache.synapse.transport.nhttp.HttpCoreNIOSender.handleException(HttpCoreNIOSender.java:683)
at org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncResponse(HttpCoreNIOSender.java:509)
at org.apache.synapse.transport.nhttp.HttpCoreNIOSender.invoke(HttpCoreNIOSender.java:293)
at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:440)
at org.apache.synapse.core.axis2.Axis2Sender.sendBack(Axis2Sender.java:138)
at org.apache.synapse.mediators.builtin.CacheMediator.processRequestMessage(CacheMediator.java:328)
at org.apache.synapse.mediators.builtin.CacheMediator.mediate(CacheMediator.java:156)
at org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:60)
at org.apache.synapse.mediators.filters.InMediator.mediate(InMediator.java:55)
at org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:60)
at org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.java:115)
at org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.java:162)
at org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:60)
at org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.java:115)
at org.apache.synapse.core.axis2.Axis2SynapseEnvironment.injectMessage(Axis2SynapseEnvironment.java:131)
at org.apache.synapse.core.axis2.SynapseMessageReceiver.receive(SynapseMessageReceiver.java:86)
at org.apache.axis2.engine.AxisEngine.receive(AxisEngine.java:178)
at org.apache.axis2.transport.http.HTTPTransportUtils.processHTTPPostRequest(HTTPTransportUtils.java:167)
at org.apache.synapse.transport.nhttp.ServerWorker.processEntityEnclosingMethod(ServerWorker.java:349)
at org.apache.synapse.transport.nhttp.ServerWorker.run(ServerWorker.java:239)
at org.apache.axis2.transport.base.threads.NativeWorkerPool$1.run(NativeWorkerPool.java:58)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:619)
Caused by: java.lang.RuntimeException: ContentID is null
at org.apache.axiom.om.impl.llom.OMTextImpl.getDataHandler(OMTextImpl.java:363)
at org.wso2.carbon.relay.ExpandingMessageFormatter.findAndWrite2OutputStream(ExpandingMessageFormatter.java:133)
at org.wso2.carbon.relay.ExpandingMessageFormatter.writeTo(ExpandingMessageFormatter.java:78)
at org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncResponse(HttpCoreNIOSender.java:476)
... 22 more
[2010-01-27 17:31:05,937] WARN - SynapseMessageReceiver Executing fault handler due to exception encountered
[2010-01-27 17:31:05,937] WARN - FaultHandler ERROR_CODE : 0
[2010-01-27 17:31:05,937] WARN - FaultHandler ERROR_MESSAGE : Unexpected error sending message back
[2010-01-27 17:31:05,937] WARN - FaultHandler ERROR_DETAIL : org.apache.synapse.SynapseException: Unexpected error sending message back
at org.apache.synapse.core.axis2.Axis2Sender.handleException(Axis2Sender.java:147)
at org.apache.synapse.core.axis2.Axis2Sender.sendBack(Axis2Sender.java:141)
at org.apache.synapse.mediators.builtin.CacheMediator.processRequestMessage(CacheMediator.java:328)
at org.apache.synapse.mediators.builtin.CacheMediator.mediate(CacheMediator.java:156)
at org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:60)
at org.apache.synapse.mediators.filters.InMediator.mediate(InMediator.java:55)
at org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:60)
at org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.java:115)
at org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.java:162)
at org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:60)
at org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.java:115)
at org.apache.synapse.core.axis2.Axis2SynapseEnvironment.injectMessage(Axis2SynapseEnvironment.java:131)
at org.apache.synapse.core.axis2.SynapseMessageReceiver.receive(SynapseMessageReceiver.java:86)
at org.apache.axis2.engine.AxisEngine.receive(AxisEngine.java:178)
at org.apache.axis2.transport.http.HTTPTransportUtils.processHTTPPostRequest(HTTPTransportUtils.java:167)
at org.apache.synapse.transport.nhttp.ServerWorker.processEntityEnclosingMethod(ServerWorker.java:349)
at org.apache.synapse.transport.nhttp.ServerWorker.run(ServerWorker.java:239)
at org.apache.axis2.transport.base.threads.NativeWorkerPool$1.run(NativeWorkerPool.java:58)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:619)
Caused by: org.apache.axis2.AxisFault: General Error sending response message to : 127.0.0.1
at org.apache.synapse.transport.nhttp.HttpCoreNIOSender.handleException(HttpCoreNIOSender.java:683)
at org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncResponse(HttpCoreNIOSender.java:509)
at org.apache.synapse.transport.nhttp.HttpCoreNIOSender.invoke(HttpCoreNIOSender.java:293)
at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:440)
at org.apache.synapse.core.axis2.Axis2Sender.sendBack(Axis2Sender.java:138)
... 19 more
Caused by: java.lang.RuntimeException: ContentID is null
at org.apache.axiom.om.impl.llom.OMTextImpl.getDataHandler(OMTextImpl.java:363)
at org.wso2.carbon.relay.ExpandingMessageFormatter.findAndWrite2OutputStream(ExpandingMessageFormatter.java:133)
at org.wso2.carbon.relay.ExpandingMessageFormatter.writeTo(ExpandingMessageFormatter.java:78)
at org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncResponse(HttpCoreNIOSender.java:476)
... 22 more
[2010-01-27 17:31:06,031] WARN - FaultHandler ERROR_EXCEPTION : org.apache.synapse.SynapseException: Unexpected error sending message back
[2010-01-27 17:31:06,031] WARN - FaultHandler FaultHandler : org.apache.synapse.mediators.MediatorFaultHandler at 19cc1e3
[2010-01-27 17:31:06,046] WARN - MediatorFaultHandler Executing fault handler mediator : fault
[2010-01-27 17:31:06,046] DEBUG - SequenceMediator Start : Sequence <fault>
[2010-01-27 17:31:06,046] DEBUG - SequenceMediator Sequence <SequenceMediator> :: mediate()
[2010-01-27 17:31:06,046] DEBUG - LogMediator Start : Log mediator
[2010-01-27 17:31:06,046] INFO - LogMediator To: , WSAction: urn:getQuote, SOAPAction: urn:getQuote, MessageID: urn:uuid:15C59E19A67E9A1DB936289301372387-1137789693, Direction: response
[2010-01-27 17:31:06,062] DEBUG - LogMediator End : Log mediator
[2010-01-27 17:31:06,062] DEBUG - SequenceMediator End : Sequence <fault>
[2010-01-27 17:31:06,062] ERROR - ServerWorker Unexpected HTTP protocol error : Transfer-encoding header already present
org.apache.http.ProtocolException: Transfer-encoding header already present
at org.apache.http.protocol.ResponseContent.process(ResponseContent.java:63)
at org.apache.http.protocol.BasicHttpProcessor.process(BasicHttpProcessor.java:211)
at org.apache.synapse.transport.nhttp.ServerHandler.commitResponse(ServerHandler.java:273)
at org.apache.synapse.transport.nhttp.ServerWorker.run(ServerWorker.java:291)
at org.apache.axis2.transport.base.threads.NativeWorkerPool$1.run(NativeWorkerPool.java:58)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:619)
[2010-01-27 17:31:06,093] ERROR - HttpCoreNIOSender Unexpected HTTP protocol error sending response to : 127.0.0.1
org.apache.http.ProtocolException: Transfer-encoding header already present
at org.apache.http.protocol.ResponseContent.process(ResponseContent.java:63)
at org.apache.http.protocol.BasicHttpProcessor.process(BasicHttpProcessor.java:211)
at org.apache.synapse.transport.nhttp.ServerHandler.commitResponse(ServerHandler.java:273)
at org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncResponse(HttpCoreNIOSender.java:464)
at org.apache.synapse.transport.nhttp.HttpCoreNIOSender.invoke(HttpCoreNIOSender.java:293)
at org.apache.axis2.engine.AxisEngine.sendFault(AxisEngine.java:523)
at org.apache.synapse.transport.nhttp.ServerWorker.handleException(ServerWorker.java:646)
at org.apache.synapse.transport.nhttp.ServerWorker.run(ServerWorker.java:297)
at org.apache.axis2.transport.base.threads.NativeWorkerPool$1.run(NativeWorkerPool.java:58)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:619)
[2010-01-27 17:31:06,125] ERROR - ServerHandler Unexpected HTTP protocol error : Transfer-encoding header already present
org.apache.http.ProtocolException: Transfer-encoding header already present
at org.apache.http.protocol.ResponseContent.process(ResponseContent.java:63)
at org.apache.http.protocol.BasicHttpProcessor.process(BasicHttpProcessor.java:211)
at org.apache.synapse.transport.nhttp.ServerHandler.commitResponseHideExceptions(ServerHandler.java:252)
at org.apache.synapse.transport.nhttp.ServerWorker.handleException(ServerWorker.java:651)
at org.apache.synapse.transport.nhttp.ServerWorker.run(ServerWorker.java:297)
at org.apache.axis2.transport.base.threads.NativeWorkerPool$1.run(NativeWorkerPool.java:58)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:619)
[2010-01-27 17:31:06,156] ERROR - NativeWorkerPool Uncaught exception
java.lang.IllegalStateException: Buffer already closed for writing
at org.apache.http.nio.util.SharedOutputBuffer.write(SharedOutputBuffer.java:142)
at org.apache.http.nio.entity.ContentOutputStream.write(ContentOutputStream.java:71)
at org.apache.synapse.transport.nhttp.ServerWorker.handleException(ServerWorker.java:654)
at org.apache.synapse.transport.nhttp.ServerWorker.run(ServerWorker.java:297)
at org.apache.axis2.transport.base.threads.NativeWorkerPool$1.run(NativeWorkerPool.java:58)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:619)
[2010-01-27 17:34:19,062] ERROR - AxisEngine Input stream has being already consumed
org.apache.axis2.AxisFault: Input stream has being already consumed
at org.apache.axis2.AxisFault.makeFault(AxisFault.java:430)
at org.wso2.carbon.relay.module.handler.SkipAdminServiceHandler.invoke(SkipAdminServiceHandler.java:126)
at org.apache.axis2.engine.Phase.invoke(Phase.java:318)
at org.apache.axis2.engine.AxisEngine.invoke(AxisEngine.java:256)
at org.apache.axis2.engine.AxisEngine.receive(AxisEngine.java:165)
at org.apache.axis2.transport.http.util.RESTUtil.invokeAxisEngine(RESTUtil.java:135)
at org.apache.axis2.transport.http.util.RESTUtil.processURLRequest(RESTUtil.java:130)
at org.apache.synapse.transport.nhttp.util.RESTUtil.processGETRequest(RESTUtil.java:130)
at org.apache.synapse.transport.nhttp.ServerWorker.processGetAndDelete(ServerWorker.java:593)
at org.apache.synapse.transport.nhttp.ServerWorker.processGet(ServerWorker.java:577)
at org.apache.synapse.transport.nhttp.ServerWorker.run(ServerWorker.java:237)
at org.apache.axis2.transport.base.threads.NativeWorkerPool$1.run(NativeWorkerPool.java:58)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:619)
Caused by: java.io.IOException: Input stream has being already consumed
at org.wso2.carbon.relay.StreamingOnRequestDataSource.getInputStream(StreamingOnRequestDataSource.java:63)
at javax.activation.DataHandler.getInputStream(DataHandler.java:238)
at org.wso2.carbon.relay.module.handler.SkipAdminServiceHandler.invoke(SkipAdminServiceHandler.java:86)
... 13 more
[2010-01-27 17:34:19,093] ERROR - ServerWorker Error processing GET request for: /
org.apache.axis2.AxisFault: Input stream has being already consumed
at org.apache.axis2.AxisFault.makeFault(AxisFault.java:430)
at org.wso2.carbon.relay.module.handler.SkipAdminServiceHandler.invoke(SkipAdminServiceHandler.java:126)
at org.apache.axis2.engine.Phase.invoke(Phase.java:318)
at org.apache.axis2.engine.AxisEngine.invoke(AxisEngine.java:256)
at org.apache.axis2.engine.AxisEngine.receive(AxisEngine.java:165)
at org.apache.axis2.transport.http.util.RESTUtil.invokeAxisEngine(RESTUtil.java:135)
at org.apache.axis2.transport.http.util.RESTUtil.processURLRequest(RESTUtil.java:130)
at org.apache.synapse.transport.nhttp.util.RESTUtil.processGETRequest(RESTUtil.java:130)
at org.apache.synapse.transport.nhttp.ServerWorker.processGetAndDelete(ServerWorker.java:593)
at org.apache.synapse.transport.nhttp.ServerWorker.processGet(ServerWorker.java:577)
at org.apache.synapse.transport.nhttp.ServerWorker.run(ServerWorker.java:237)
at org.apache.axis2.transport.base.threads.NativeWorkerPool$1.run(NativeWorkerPool.java:58)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:619)
Caused by: java.io.IOException: Input stream has being already consumed
at org.wso2.carbon.relay.StreamingOnRequestDataSource.getInputStream(StreamingOnRequestDataSource.java:63)
at javax.activation.DataHandler.getInputStream(DataHandler.java:238)
at org.wso2.carbon.relay.module.handler.SkipAdminServiceHandler.invoke(SkipAdminServiceHandler.java:86)
... 13 more
--
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators: https://wso2.org/jira/secure/Administrators.jspa
-
For more information on JIRA, see: http://www.atlassian.com/software/jira
More information about the Carbon-jira
mailing list