[mule-user] re[mule-user] quest/response using axis and async-reply

0 views

Skip to first unread message

Cuball

unread,

Feb 16, 2009, 3:28:12 AM2/16/09

to us...@mule.codehaus.org

I have defined and service using an axis webservice as inbound-endpoint. I
send the received message to a jms queue. Then I wait until the processing
is done by an external system, when the processing is done this system posts
an message on queue. This queue is defined as an inboud-endpoint on my
async-reply router.

Everything goes as planned until the message should be returned to the
client, nothing happens...

this is my configuration:

<jms:activemq-connector
name="jmsConnector"
specification="1.1"
persistentDelivery="true"
connectionFactory-ref="connectionFactory" />

<axis:connector name="axisConnector"
doAutoTypes="false" >

<axis:bean-type interface="be.xxx.mule.domain.remote.RemotePrintJob" />
<axis:bean-type
interface="be.xxx.mule.domain.remote.RemotePrintJobMetaData" />
<axis:bean-type interface="be.xxx.mule.domain.remote.RemoteLetter" />
<axis:bean-type interface="be.xxx.mule.domain.remote.RemoteLetterMetaData"
/>
<axis:bean-type interface="be.xxx.mule.domain.remote.RemoteHeaderData" />
<axis:bean-type interface="be.xxx.mule.domain.remote.RemoteFooterData" />
<axis:bean-type interface="be.xxx.mule.domain.remote.RemoteVariable" />
<axis:bean-type interface="be.xxx.mule.domain.remote.RemoteVariableValue"
/>
<axis:bean-type interface="be.xxx.mule.domain.remote.PrintJobResult" />
<axis:bean-type interface="be.xxx.mule.domain.remote.PrintJobResultItem"
/>

</axis:connector>

this is my logging output:

**********************************************************************
* Mule ESB and Integration Platform *
* Version: 2.1.2 Build: 13558 *
* MuleSource, Inc. *
* For more information go to http://mule.mulesource.org *
* *
* Server started: 16/02/09 9:20 *
* Server ID: b7504c89-fc02-11dd-a580-6b442b53c3c9 *
* JDK: 1.6.0_06 (mixed mode) *
* Encoding: OS: Cp1252, Mule: UTF-8 *
* OS: Windows Vista - Service Pack 1 (6.0, x86) *
* Host: W501-115D (10.35.205.1) *
* *
* Agents Running: *
* jmx-log4j *
* Wrapper Manager: Mule PID #5792, Wrapper PID #6624 *
* Rmi Registry: rmi://localhost:1099 *
* Jmx Notification Agent (Listener MBean registered) *
* jmx-agent: service:jmx:rmi:///jndi/rmi://localhost:1099/server *
* MX4J Http adaptor: http://localhost:9999 *
* Default Jmx Support Agent *
**********************************************************************
DEBUG 2009-02-16 09:21:03,762 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:21:03,902 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:21:13,778 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:21:13,918 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:21:23,793 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:21:23,934 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:21:33,809 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:21:33,949 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:21:43,824 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:21:43,964 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:21:53,839 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:21:53,979 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:21:56,582 [connector.http.0.receiver.1]
org.mule.retry.notifiers.ConnectNotifier: Successfully connected to
http://w501-115d:81/services/OnDemandPrintService
DEBUG 2009-02-16 09:21:56,613 [connector.http.0.receiver.2]
org.mule.util.monitor.ExpiryMonitor: Removing expirable:
org.mule.transport.http.HttpMessageReceiver$HttpWorker@d246ce
DEBUG 2009-02-16 09:21:56,629 [connector.http.0.receiver.2]
org.mule.transport.http.HttpMessageReceiver: /services/OnDemandPrintService
DEBUG 2009-02-16 09:21:56,629 [connector.http.0.receiver.2]
org.mule.transport.http.HttpMessageReceiver: Secondary lookup of receiver on
connector: connector.http.0 with URI key:
http://w501-115d:81/services/OnDemandPrintService
DEBUG 2009-02-16 09:21:56,629 [connector.http.0.receiver.2]
org.mule.transport.http.HttpMessageReceiver: Message Received from:
http://w501-115d:81/services/OnDemandPrintService
DEBUG 2009-02-16 09:21:56,629 [connector.http.0.receiver.2]
org.mule.DefaultMuleSession: There is no session id on the request using
key: ID. Generating new session id: dfd3648c-fc02-11dd-a580-6b442b53c3c9
DEBUG 2009-02-16 09:21:56,644 [connector.http.0.receiver.2]
org.mule.DefaultMuleSession: sending event to service:
_axisServiceaxisConnector event is: MuleEvent:
dfd3648d-fc02-11dd-a580-6b442b53c3c9, sync=true, stop processing=false,
DefaultInboundEndpoint{endpointUri=http://w501-115d:81/services/OnDemandPrintService,
connector=HttpConnector{this=5017e9, started=true, initialised=true,
name='connector.http.0', disposed=false,
numberOfConcurrentTransactedReceivers=4,
createMultipleTransactedReceivers=true, connected=true,
supportedProtocols=[http], serviceOverrides=null}, transformer=[],
name='http:OnDemandPrintService', properties={},
transactionConfig=Transaction{factory=null, action=NEVER, timeout=0},
filter=null, deleteUnacceptedMessages=false, securityFilter=null,
synchronous=true, initialState=started, remoteSync=false,
remoteSyncTimeout=10000, endpointEncoding=UTF-8}
DEBUG 2009-02-16 09:21:56,644 [connector.http.0.receiver.2]
org.mule.model.seda.SedaService: Service: _axisServiceaxisConnector has
received synchronous event on:
http://w501-115d:81/services/OnDemandPrintService
DEBUG 2009-02-16 09:21:56,644 [connector.http.0.receiver.2]
org.mule.model.seda.SedaService: _axisServiceaxisConnector : got proxy for
dfd3648d-fc02-11dd-a580-6b442b53c3c9 =
org.mule.component.DefaultJavaComponent component for:
_axisServiceaxisConnector
DEBUG 2009-02-16 09:21:56,644 [connector.http.0.receiver.2]
org.mule.transport.soap.axis.AxisServiceComponent:
MessageContext:org.apache.axis.MessageContext@1e604da
DEBUG 2009-02-16 09:21:56,644 [connector.http.0.receiver.2]
org.mule.transport.soap.axis.AxisServiceComponent:
HEADER_CONTENT_TYPE:text/xml; charset=utf-8
DEBUG 2009-02-16 09:21:56,644 [connector.http.0.receiver.2]
org.mule.transport.soap.axis.AxisServiceComponent:
HEADER_CONTENT_LOCATION:null
DEBUG 2009-02-16 09:21:56,644 [connector.http.0.receiver.2]
org.mule.transport.soap.axis.AxisServiceComponent:
Constants.MC_HOME_DIR:/axisHome
DEBUG 2009-02-16 09:21:56,644 [connector.http.0.receiver.2]
org.mule.transport.soap.axis.AxisServiceComponent:
Constants.MC_RELATIVE_PATH:/services/OnDemandPrintService
DEBUG 2009-02-16 09:21:56,644 [connector.http.0.receiver.2]
org.mule.transport.soap.axis.AxisServiceComponent:
HTTPConstants.HEADER_AUTHORIZATION:null
DEBUG 2009-02-16 09:21:56,644 [connector.http.0.receiver.2]
org.mule.transport.soap.axis.AxisServiceComponent:
Constants.MC_REMOTE_ADDR:w501-115d
DEBUG 2009-02-16 09:21:56,691 [connector.http.0.receiver.2]
org.mule.transport.soap.axis.AxisServiceComponent: Request
Message:org.apache.axis.Message@1ae13c0
DEBUG 2009-02-16 09:21:56,691 [connector.http.0.receiver.2]
org.mule.transport.soap.axis.AxisServiceComponent: Header Soap Action:""
DEBUG 2009-02-16 09:21:56,691 [connector.http.0.receiver.2]
org.mule.transport.soap.axis.AxisServiceComponent: Invoking Axis Engine.
DEBUG 2009-02-16 09:21:56,988 [connector.http.0.receiver.2]
org.mule.transport.soap.axis.AxisMessageReceiver: Message Received from:
http://w501-115d:81/services
DEBUG 2009-02-16 09:21:56,988 [connector.http.0.receiver.2]
org.mule.DefaultMuleSession: There is no session id on the request using
key: ID. Generating new session id: e00a2c00-fc02-11dd-a580-6b442b53c3c9
DEBUG 2009-02-16 09:21:56,988 [connector.http.0.receiver.2]
org.mule.DefaultMuleSession: sending event to service: OnDemandPrintService
event is: MuleEvent: e00a2c01-fc02-11dd-a580-6b442b53c3c9, sync=true, stop
processing=false,
DefaultInboundEndpoint{endpointUri=http://w501-115d:81/services,
connector=AxisConnector{this=1575c7e, started=true, initialised=true,
name='axisConnector', disposed=false,
numberOfConcurrentTransactedReceivers=4,
createMultipleTransactedReceivers=true, connected=true,
supportedProtocols=[axis, axis:http, axis:https, axis:servlet, axis:vm,
axis:jms, axis:xmpp, axis:ssl, axis:tcp, axis:smtp, axis:smtps, axis:pop3,
axis:pop3s, axis:imap, axis:imaps, axis:http, axis:https, axis:servlet,
axis:vm, axis:jms, axis:xmpp, axis:ssl, axis:tcp, axis:smtp, axis:smtps,
axis:pop3, axis:pop3s, axis:imap, axis:imaps], serviceOverrides=null},
transformer=[], name='endpoint.http.w501.115d.81.services',
properties={serviceInterfaces=[be.xxx.mule.service.OnDemandPrintService],
serviceNamespace=http://w501-115d:81/services/OnDemandPrintService,
axisOptions={wsdlServiceElement=RpcOnDemandPrintServiceEndpoint,
wsdlPortjType=OnDemandPrintService}},
transactionConfig=Transaction{factory=null, action=NEVER, timeout=0},
filter=null, deleteUnacceptedMessages=false, securityFilter=null,
synchronous=true, initialState=started, remoteSync=true,
remoteSyncTimeout=10000, endpointEncoding=UTF-8}
DEBUG 2009-02-16 09:21:56,988 [connector.http.0.receiver.2]
org.mule.model.seda.SedaService: Service: OnDemandPrintService has received
synchronous event on: http://w501-115d:81/services
DEBUG 2009-02-16 09:21:56,988 [connector.http.0.receiver.2]
org.mule.model.seda.SedaService: OnDemandPrintService : got proxy for
e00a2c01-fc02-11dd-a580-6b442b53c3c9 =
org.mule.component.DefaultJavaComponent component for: OnDemandPrintService
DEBUG 2009-02-16 09:21:56,988 [connector.http.0.receiver.2]
org.mule.model.resolvers.MethodHeaderPropertyEntryPointResolver: Invoking
be.xxx.mule.component.OnDemandPrintComponent.print({class
be.xxx.mule.domain.remote.RemotePrintJob})
DEBUG 2009-02-16 09:21:56,988 [connector.http.0.receiver.2]
org.mule.model.resolvers.MethodHeaderPropertyEntryPointResolver: Result of
call be.xxx.mule.component.OnDemandPrintComponent.print({class
be.xxx.mule.domain.remote.RemotePrintJob}) is not null
DEBUG 2009-02-16 09:21:57,003 [connector.http.0.receiver.2]
org.mule.transformer.TransformerTemplate: Applying transformer
TransformerTemplate (org.mule.transformer.TransformerTemplate)
DEBUG 2009-02-16 09:21:57,003 [connector.http.0.receiver.2]
org.mule.transformer.TransformerTemplate: Object before transform:
org.mule.transport.soap.axis.AxisMessageAdapter/org.mule.transport.soap.axis.AxisMessageAdapter@8dbf31{id=e00549ff-fc02-11dd-a580-6b442b53c3c9,
payload=[Ljava.lang.Object;, correlationId=null, correla...
DEBUG 2009-02-16 09:21:57,003 [connector.http.0.receiver.2]
org.mule.transformer.TransformerTemplate: Object after transform:
be.xxx.mule.domain.remote.RemotePrintJob@340d1
DEBUG 2009-02-16 09:21:57,003 [connector.http.0.receiver.2]
org.mule.transformer.TransformerTemplate: The transformed object is of
expected type. Type is: RemotePrintJob
DEBUG 2009-02-16 09:21:57,003 [connector.http.0.receiver.2]
org.mule.routing.outbound.OutboundPassThroughRouter: No CorrelationId is set
on the message, will set a new Id
DEBUG 2009-02-16 09:21:57,003 [connector.http.0.receiver.2]
org.mule.routing.outbound.OutboundPassThroughRouter: Extracted correlation
Id as: e00549ff-fc02-11dd-a580-6b442b53c3c9
DEBUG 2009-02-16 09:21:57,003 [connector.http.0.receiver.2]
org.mule.routing.outbound.OutboundPassThroughRouter: Setting Correlation
info on Outbound router for endpoint: jms://syncPrintJobs
Id=e00549ff-fc02-11dd-a580-6b442b53c3c9
DEBUG 2009-02-16 09:21:57,081 [connector.http.0.receiver.2]
org.mule.routing.outbound.OutboundPassThroughRouter: Message being sent to:
jms://syncPrintJobs
DEBUG 2009-02-16 09:21:57,081 [connector.http.0.receiver.2]
org.mule.routing.outbound.OutboundPassThroughRouter:
org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@1e3752b{id=e00549ff-fc02-11dd-a580-6b442b53c3c9,
payload=be.xxx.mule.domain.remote.RemotePrintJob,
correlationId=e00549ff-fc02-11dd-a580-6b442b53c3c9, correlationGroup=-1,
correlationSeq=-1, encoding=UTF-8, exceptionPayload=null,
properties=Properites{invocation:{}, inbound:{},
outbound:{http.request=/services/OnDemandPrintService,
http.version=HTTP/1.0, MULE_ENCODING=UTF-8,
serviceInterfaces=[be.xxx.mule.service.OnDemandPrintService],
MULE_CORRELATION_ID=e00549ff-fc02-11dd-a580-6b442b53c3c9, http.method=POST,
serviceNamespace=http://w501-115d:81/services/OnDemandPrintService,
SOAPAction="", MULE_REMOTE_SYNC=true,
MULE_ORIGINATING_ENDPOINT=endpoint.http.w501.115d.81.services,
axisOptions={wsdlServiceElement=RpcOnDemandPrintServiceEndpoint,
wsdlPortjType=OnDemandPrintService}}, session:{}, }}
DEBUG 2009-02-16 09:21:57,081 [connector.http.0.receiver.2]
org.mule.DefaultMuleSession: MuleSession has received synchronous event on
endpoint: DefaultOutboundEndpoint{endpointUri=jms://syncPrintJobs,
connector=ActiveMQJmsConnector{this=14c4066, started=true, initialised=true,
name='jmsConnector', disposed=false,
numberOfConcurrentTransactedReceivers=4,
createMultipleTransactedReceivers=true, connected=true,
supportedProtocols=[jms], serviceOverrides=null},
transformer=[RemotePrintJobToPrintJob{this=174689,
name='RemotePrintJobToPrintJob', ignoreBadInput=false, returnClass=class
be.xxx.mule.domain.PrintJob, sourceTypes=[]},
ObjectToJMSMessage{this=1800bc, name='ObjectToJMSMessage',
ignoreBadInput=false, returnClass=interface javax.jms.Message,
sourceTypes=[]}], name='endpoint.jms.syncPrintJobs', properties={},
transactionConfig=Transaction{factory=null, action=NEVER, timeout=0},
filter=null, deleteUnacceptedMessages=false, securityFilter=null,
synchronous=true, initialState=started, remoteSync=false,
remoteSyncTimeout=10000, endpointEncoding=UTF-8}
DEBUG 2009-02-16 09:21:57,081 [connector.http.0.receiver.2]
org.mule.DefaultMuleSession: Creating event with data:
be.xxx.mule.domain.remote.RemotePrintJob, for endpoint:
DefaultOutboundEndpoint{endpointUri=jms://syncPrintJobs,
connector=ActiveMQJmsConnector{this=14c4066, started=true, initialised=true,
name='jmsConnector', disposed=false,
numberOfConcurrentTransactedReceivers=4,
createMultipleTransactedReceivers=true, connected=true,
supportedProtocols=[jms], serviceOverrides=null},
transformer=[RemotePrintJobToPrintJob{this=174689,
name='RemotePrintJobToPrintJob', ignoreBadInput=false, returnClass=class
be.xxx.mule.domain.PrintJob, sourceTypes=[]},
ObjectToJMSMessage{this=1800bc, name='ObjectToJMSMessage',
ignoreBadInput=false, returnClass=interface javax.jms.Message,
sourceTypes=[]}], name='endpoint.jms.syncPrintJobs', properties={},
transactionConfig=Transaction{factory=null, action=NEVER, timeout=0},
filter=null, deleteUnacceptedMessages=false, securityFilter=null,
synchronous=true, initialState=started, remoteSync=false,
remoteSyncTimeout=10000, endpointEncoding=UTF-8}
DEBUG 2009-02-16 09:21:57,097 [connector.http.0.receiver.2]
org.mule.DefaultMuleSession: sending event: MuleEvent:
e01acdd4-fc02-11dd-a580-6b442b53c3c9, sync=false, stop processing=false,
DefaultOutboundEndpoint{endpointUri=jms://syncPrintJobs,
connector=ActiveMQJmsConnector{this=14c4066, started=true, initialised=true,
name='jmsConnector', disposed=false,
numberOfConcurrentTransactedReceivers=4,
createMultipleTransactedReceivers=true, connected=true,
supportedProtocols=[jms], serviceOverrides=null},
transformer=[RemotePrintJobToPrintJob{this=174689,
name='RemotePrintJobToPrintJob', ignoreBadInput=false, returnClass=class
be.xxx.mule.domain.PrintJob, sourceTypes=[]},
ObjectToJMSMessage{this=1800bc, name='ObjectToJMSMessage',
ignoreBadInput=false, returnClass=interface javax.jms.Message,
sourceTypes=[]}], name='endpoint.jms.syncPrintJobs', properties={},
transactionConfig=Transaction{factory=null, action=NEVER, timeout=0},
filter=null, deleteUnacceptedMessages=false, securityFilter=null,
synchronous=true, initialState=started, remoteSync=false,
remoteSyncTimeout=10000, endpointEncoding=UTF-8}
DEBUG 2009-02-16 09:21:57,112 [connector.http.0.receiver.2]
org.mule.MuleSessionHandler: Adding session header to message:
ID=e00a2c00-fc02-11dd-a580-6b442b53c3c9
DEBUG 2009-02-16 09:21:57,112 [connector.http.0.receiver.2]
org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder
(org.mule.transformer.codec.Base64Encoder)
DEBUG 2009-02-16 09:21:57,112 [connector.http.0.receiver.2]
org.mule.transformer.codec.Base64Encoder: Object before transform:
ID=e00a2c00-fc02-11dd-a580-6b442b53c3c9
DEBUG 2009-02-16 09:21:57,112 [connector.http.0.receiver.2]
org.mule.transformer.codec.Base64Encoder: Object after transform:
SUQ9ZTAwYTJjMDAtZmMwMi0xMWRkLWE1ODAtNmI0NDJiNTNjM2M5
DEBUG 2009-02-16 09:21:57,112 [connector.http.0.receiver.2]
org.mule.transformer.codec.Base64Encoder: The transformed object is of
expected type. Type is: String
DEBUG 2009-02-16 09:21:57,112 [connector.http.0.receiver.2]
org.mule.transport.jms.activemq.ActiveMQJmsConnector: Borrowing a dispatcher
for endpoint: jms://syncPrintJobs
DEBUG 2009-02-16 09:21:57,128 [connector.http.0.receiver.2]
org.mule.transport.jms.activemq.ActiveMQJmsConnector: Borrowed a dispatcher
for endpoint: jms://syncPrintJobs = JmsMessageDispatcher{this=757fad,
endpoint=jms://syncPrintJobs, disposed=false}
DEBUG 2009-02-16 09:21:57,128 [connector.http.0.receiver.2]
org.mule.transport.jms.activemq.ActiveMQJmsConnector: Borrowed dispatcher:
JmsMessageDispatcher{this=757fad, endpoint=jms://syncPrintJobs,
disposed=false}
DEBUG 2009-02-16 09:21:57,128 [connector.http.0.receiver.2]
org.mule.retry.notifiers.ConnectNotifier: Successfully connected to
endpoint.outbound.jms://syncPrintJobs
DEBUG 2009-02-16 09:21:57,128 [connector.http.0.receiver.2]
org.mule.transport.jms.JmsMessageDispatcher: dispatching on endpoint:
jms://syncPrintJobs. MuleEvent id is: e01acdd4-fc02-11dd-a580-6b442b53c3c9.
Outbound transformers are: [RemotePrintJobToPrintJob{this=174689,
name='RemotePrintJobToPrintJob', ignoreBadInput=false, returnClass=class
be.xxx.mule.domain.PrintJob, sourceTypes=[]},
ObjectToJMSMessage{this=1800bc, name='ObjectToJMSMessage',
ignoreBadInput=false, returnClass=interface javax.jms.Message,
sourceTypes=[]}]
DEBUG 2009-02-16 09:21:57,128 [connector.http.0.receiver.2]
org.mule.transport.jms.activemq.ActiveMQJmsConnector: Retrieving new jms
session from connection: topic=false, transacted=false, ack mode=1,
nolocal=false
DEBUG 2009-02-16 09:21:57,128 [connector.http.0.receiver.2]
org.mule.transport.jms.Jms11Support: Using non-JNDI destination
syncPrintJobs, will create one now
DEBUG 2009-02-16 09:21:57,128 [connector.http.0.receiver.2]
be.xxx.mule.transformer.RemotePrintJobToPrintJob: Applying transformer
RemotePrintJobToPrintJob (be.xxx.mule.transformer.RemotePrintJobToPrintJob)
DEBUG 2009-02-16 09:21:57,128 [connector.http.0.receiver.2]
be.xxx.mule.transformer.RemotePrintJobToPrintJob: Object before transform:
be.xxx.mule.domain.remote.RemotePrintJob@340d1
DEBUG 2009-02-16 09:21:57,144 [connector.http.0.receiver.2]
be.xxx.mule.transformer.RemotePrintJobToPrintJob: Object after transform:
be.xxx.mule.domain.PrintJob@335aa
DEBUG 2009-02-16 09:21:57,144 [connector.http.0.receiver.2]
be.xxx.mule.transformer.RemotePrintJobToPrintJob: The transformed object is
of expected type. Type is: PrintJob
DEBUG 2009-02-16 09:21:57,144 [connector.http.0.receiver.2]
org.mule.transport.jms.transformers.ObjectToJMSMessage: Applying transformer
ObjectToJMSMessage (org.mule.transport.jms.transformers.ObjectToJMSMessage)
DEBUG 2009-02-16 09:21:57,144 [connector.http.0.receiver.2]
org.mule.transport.jms.transformers.ObjectToJMSMessage: Object before
transform:
org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@1e3752b{id=e00549ff-fc02-11dd-a580-6b442b53c3c9,
payload=be.xxx.mule.domain.PrintJob, correlationId=e00549ff-fc02-11dd-...
DEBUG 2009-02-16 09:21:57,144 [connector.http.0.receiver.2]
org.mule.transport.jms.transformers.ObjectToJMSMessage: Source object is
PrintJob
DEBUG 2009-02-16 09:21:57,144 [connector.http.0.receiver.2]
org.mule.transport.jms.activemq.ActiveMQJmsConnector: Retrieving new jms
session from connection: topic=false, transacted=false, ack mode=1,
nolocal=false
DEBUG 2009-02-16 09:21:57,175 [connector.http.0.receiver.2]
org.mule.transport.jms.transformers.ObjectToJMSMessage: Closing
non-transacted jms session: ActiveMQSession
{id=ID:W501-115D-49747-1234772453076-0:0:6,started=true}
DEBUG 2009-02-16 09:21:57,175 [connector.http.0.receiver.2]
org.mule.transport.jms.transformers.ObjectToJMSMessage: Resulting object is
ActiveMQObjectMessage
DEBUG 2009-02-16 09:21:57,190 [connector.http.0.receiver.2]
org.mule.transport.jms.transformers.ObjectToJMSMessage: Object after
transform: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
messageId = null, originalDestination = null, originalTransactionId = null,
producerId = null, destination = null, transactionId = null,...
DEBUG 2009-02-16 09:21:57,190 [connector.http.0.receiver.2]
org.mule.transport.jms.transformers.ObjectToJMSMessage: The transformed
object is of expected type. Type is: ActiveMQObjectMessage
DEBUG 2009-02-16 09:21:57,190 [connector.http.0.receiver.2]
org.mule.transport.jms.JmsMessageDispatcher: Sending message of type
ActiveMQObjectMessage
DEBUG 2009-02-16 09:21:57,190 [connector.http.0.receiver.2]
org.mule.transport.jms.activemq.ActiveMQJmsConnector: Returning dispatcher
for endpoint: jms://syncPrintJobs = JmsMessageDispatcher{this=757fad,
endpoint=jms://syncPrintJobs, disposed=false}
DEBUG 2009-02-16 09:21:57,190 [connector.http.0.receiver.2]
org.mule.routing.outbound.OutboundPassThroughRouter: Response message from
sending to: jms://syncPrintJobs
DEBUG 2009-02-16 09:21:57,190 [connector.http.0.receiver.2]
org.mule.routing.outbound.OutboundPassThroughRouter: null
DEBUG 2009-02-16 09:21:57,190 [connector.http.0.receiver.2]
org.mule.model.seda.SedaService: Waiting for response router message
DEBUG 2009-02-16 09:22:03,841 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:22:03,982 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:22:04,715 [connector.http.0.receiver.2]
org.mule.routing.EventCorrelator: Waiting for response for message id:
e00549ff-fc02-11dd-a580-6b442b53c3c9 in
org.mule.routing.EventCorrelator@13382af
DEBUG 2009-02-16 09:22:04,715 [connector.http.0.receiver.2]
org.mule.routing.EventCorrelator: Got response but no one is waiting for it
yet. Creating latch for e00549ff-fc02-11dd-a580-6b442b53c3c9 in
org.mule.routing.EventCorrelator@13382af
DEBUG 2009-02-16 09:22:04,715 [connector.http.0.receiver.2]
org.mule.routing.EventCorrelator: Got latch for message:
e00549ff-fc02-11dd-a580-6b442b53c3c9
DEBUG 2009-02-16 09:22:04,715 [connector.http.0.receiver.2]
org.mule.routing.EventCorrelator: Waiting for response to message:
e00549ff-fc02-11dd-a580-6b442b53c3c9
DEBUG 2009-02-16 09:22:13,857 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:22:13,997 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:22:18,834 [ActiveMQ Session Task]
org.mule.transport.jms.MultiConsumerJmsMessageReceiver: Message received it
is of type: ActiveMQObjectMessage
DEBUG 2009-02-16 09:22:18,834 [ActiveMQ Session Task]
org.mule.transport.jms.MultiConsumerJmsMessageReceiver: Message received on
queue://scripturaResult (org.apache.activemq.command.ActiveMQQueue)
DEBUG 2009-02-16 09:22:18,834 [ActiveMQ Session Task]
org.mule.transport.jms.MultiConsumerJmsMessageReceiver: Message
CorrelationId is: e00549ff-fc02-11dd-a580-6b442b53c3c9
DEBUG 2009-02-16 09:22:18,834 [ActiveMQ Session Task]
org.mule.transport.jms.MultiConsumerJmsMessageReceiver: Jms Message Id is:
ID:W501-115D-49430-1234770379026-0:2:1:1:1
DEBUG 2009-02-16 09:22:18,834 [ActiveMQ Session Task]
org.mule.transport.jms.MultiConsumerJmsMessageReceiver: Message Received
from: jms://scripturaResult
DEBUG 2009-02-16 09:22:18,834 [ActiveMQ Session Task]
org.mule.DefaultMuleSession: There is no session id on the request using
key: ID. Generating new session id: ed0f9b66-fc02-11dd-a580-6b442b53c3c9
DEBUG 2009-02-16 09:22:18,834 [ActiveMQ Session Task]
be.xxx.mule.transformer.JMSMessageToPrintJobResult: Applying transformer
JMSMessageToPrintJobResult
(be.xxx.mule.transformer.JMSMessageToPrintJobResult)
DEBUG 2009-02-16 09:22:18,834 [ActiveMQ Session Task]
be.xxx.mule.transformer.JMSMessageToPrintJobResult: Object before transform:
ActiveMQObjectMessage {commandId = 5, responseRequired = true, messageId =
ID:W501-115D-49430-1234770379026-0:2:1:1:1, originalDestination = null,
originalTransactionId = null, producerId = ID:W501-11...
DEBUG 2009-02-16 09:22:18,834 [ActiveMQ Session Task]
be.xxx.mule.transformer.JMSMessageToPrintJobResult: Object after transform:
be.xxx.mule.domain.remote.PrintJobResult@3ec646
DEBUG 2009-02-16 09:22:18,834 [ActiveMQ Session Task]
be.xxx.mule.transformer.JMSMessageToPrintJobResult: The transformed object
is of expected type. Type is: PrintJobResult
DEBUG 2009-02-16 09:22:18,850 [ActiveMQ Session Task]
org.mule.routing.EventCorrelator: Adding event to response aggregator group:
ID:W501-115D-49430-1234770379026-0:2:1:1:1
WARN 2009-02-16 09:22:18,850 [ActiveMQ Session Task]
org.mule.routing.CollectionCorrelatorCallback: Correlation Group Size not
set, but CorrelationAggregator is being used. Message is being forwarded
DEBUG 2009-02-16 09:22:18,850 [ActiveMQ Session Task]
org.mule.routing.EventCorrelator: Creating latch for
ID:W501-115D-49430-1234770379026-0:2:1:1:1 in
org.mule.routing.EventCorrelator@13382af
DEBUG 2009-02-16 09:22:23,858 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:22:23,998 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:22:33,873 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:22:34,014 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:22:43,889 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:22:44,029 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:22:53,890 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:22:54,030 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:23:03,901 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:23:04,042 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:23:13,917 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:23:14,057 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:23:23,933 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:23:24,073 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:23:33,948 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:23:34,088 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:23:43,964 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:23:44,104 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
ERROR 2009-02-16 09:23:44,728 [connector.http.0.receiver.2]
org.mule.service.DefaultServiceExceptionStrategy:
********************************************************************************
Message : Response timed out (100000ms) waiting for message
response id "e00549ff-fc02-11dd-a580-6b442b53c3c9" or this action was
interrupted.. Failed to route event via endpoint: null. Message payload is
of type: ActiveMQObjectMessage
Type : org.mule.api.routing.ResponseTimeoutException
Code : MULE_ERROR-40999
Payload : ActiveMQObjectMessage {commandId = 0,
responseRequired = false, messageId =
ID:W501-115D-49747-1234772453076-0:0:5:1:1, originalDestination = null,
originalTransactionId = null, producerId = null, destination =
queue://syncPrintJobs, transactionId = null, expiration = 0, timestamp =
1234772517190, arrival = 0, brokerInTime = 0, brokerOutTime = 0,
correlationId = e00549ff-fc02-11dd-a580-6b442b53c3c9, replyTo = null,
persistent = true, type = null, priority = 4, groupID = null, groupSequence
= 0, targetConsumerId = null, compressed = false, userID = null, content =
org.apache.activemq.util.ByteSequence@108beb8, marshalledProperties = null,
dataStructure = null, redeliveryCounter = 0, size = 0, properties =
{http_method=POST,
MULE_SESSION=SUQ9ZTAwYTJjMDAtZmMwMi0xMWRkLWE1ODAtNmI0NDJiNTNjM2M5,
http_request=/services/OnDemandPrintService,
serviceInterfaces=[be.xxx.mule.service.OnDemandPrintService],
MULE_ENCODING=UTF-8, http_version=HTTP/1.0,
MULE_CORRELATION_ID=e00549ff-fc02-11dd-a580-6b442b53c3c9,
serviceNamespace=http://w501-115d:81/services/OnDemandPrintService,
SOAPAction="", MULE_ENDPOINT=jms://syncPrintJobs,
axisOptions={wsdlServiceElement=RpcOnDemandPrintServiceEndpoint,
wsdlPortjType=OnDemandPrintService},
MULE_ORIGINATING_ENDPOINT=endpoint.http.w501.115d.81.services},
readOnlyProperties = false, readOnlyBody = false, droppable = false}
JavaDoc :
http://www.mulesource.org/docs/site/current2/apidocs/org/mule/api/routing/ResponseTimeoutException.html
********************************************************************************
Exception stack is:
1. Response timed out (100000ms) waiting for message response id
"e00549ff-fc02-11dd-a580-6b442b53c3c9" or this action was interrupted..
Failed to route event via endpoint: null. Message payload is of type:
ActiveMQObjectMessage (org.mule.api.routing.ResponseTimeoutException)
org.mule.routing.EventCorrelator:471
(http://www.mulesource.org/docs/site/current2/apidocs/org/mule/api/routing/ResponseTimeoutException.html)
********************************************************************************
Root Exception stack trace:
org.mule.api.routing.ResponseTimeoutException: Response timed out (100000ms)
waiting for message response id "e00549ff-fc02-11dd-a580-6b442b53c3c9" or
this action was interrupted.. Failed to route event via endpoint: null.
Message payload is of type: ActiveMQObjectMessage
at org.mule.routing.EventCorrelator.getResponse(EventCorrelator.java:471)
at org.mule.routing.EventCorrelator.getResponse(EventCorrelator.java:374)
at
org.mule.routing.response.AbstractResponseAggregator.getResponse(AbstractResponseAggregator.java:73)
at
org.mule.routing.response.DefaultResponseRouterCollection.getResponse(DefaultResponseRouterCollection.java:90)
at
org.mule.service.AbstractService.processAsyncReplyRouter(AbstractService.java:926)
at org.mule.model.seda.SedaService.doSend(SedaService.java:236)
at org.mule.service.AbstractService.sendEvent(AbstractService.java:506)
at org.mule.DefaultMuleSession.sendEvent(DefaultMuleSession.java:354)
at
org.mule.routing.inbound.DefaultInboundRouterCollection.send(DefaultInboundRouterCollection.java:220)
at
org.mule.routing.inbound.DefaultInboundRouterCollection.route(DefaultInboundRouterCollection.java:180)
at
org.mule.transport.AbstractMessageReceiver$DefaultInternalMessageListener.onMessage(AbstractMessageReceiver.java:365)
at
org.mule.transport.AbstractMessageReceiver.routeMessage(AbstractMessageReceiver.java:253)
at
org.mule.transport.AbstractMessageReceiver.routeMessage(AbstractMessageReceiver.java:176)
at
org.mule.transport.soap.axis.AxisServiceProxy$AxisServiceHandler.invoke(AxisServiceProxy.java:84)
at $Proxy0.print(Unknown Source)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at
org.apache.axis.providers.java.RPCProvider.invokeMethod(RPCProvider.java:397)
at
org.apache.axis.providers.java.RPCProvider.processMessage(RPCProvider.java:186)
at
org.apache.axis.providers.java.JavaProvider.invoke(JavaProvider.java:323)
at
org.mule.transport.soap.axis.extensions.MuleRPCProvider.invoke(MuleRPCProvider.java:99)
at
org.apache.axis.strategies.InvocationStrategy.visit(InvocationStrategy.java:32)
at org.apache.axis.SimpleChain.doVisiting(SimpleChain.java:118)
at org.apache.axis.SimpleChain.invoke(SimpleChain.java:83)
at org.apache.axis.handlers.soap.SOAPService.invoke(SOAPService.java:454)
at org.apache.axis.server.AxisServer.invoke(AxisServer.java:281)
at
org.mule.transport.soap.axis.AxisServiceComponent.doPost(AxisServiceComponent.java:580)
at
org.mule.transport.soap.axis.AxisServiceComponent.onCall(AxisServiceComponent.java:131)
at
org.mule.model.resolvers.CallableEntryPointResolver.invoke(CallableEntryPointResolver.java:52)
at
org.mule.model.resolvers.DefaultEntryPointResolverSet.invoke(DefaultEntryPointResolverSet.java:50)
at
org.mule.component.DefaultLifecycleAdapter.invoke(DefaultLifecycleAdapter.java:197)
at
org.mule.component.AbstractJavaComponent.invokeComponentInstance(AbstractJavaComponent.java:83)
at
org.mule.component.AbstractJavaComponent.doInvoke(AbstractJavaComponent.java:74)
at
org.mule.component.AbstractComponent.invokeInternal(AbstractComponent.java:125)
at org.mule.component.AbstractComponent.invoke(AbstractComponent.java:153)
at
org.mule.service.AbstractService.invokeComponent(AbstractService.java:933)
at org.mule.model.seda.SedaService.doSend(SedaService.java:234)
at org.mule.service.AbstractService.sendEvent(AbstractService.java:506)
at org.mule.DefaultMuleSession.sendEvent(DefaultMuleSession.java:354)
at
org.mule.routing.inbound.DefaultInboundRouterCollection.send(DefaultInboundRouterCollection.java:220)
at
org.mule.routing.inbound.DefaultInboundRouterCollection.route(DefaultInboundRouterCollection.java:180)
at
org.mule.transport.AbstractMessageReceiver$DefaultInternalMessageListener.onMessage(AbstractMessageReceiver.java:365)
at
org.mule.transport.AbstractMessageReceiver.routeMessage(AbstractMessageReceiver.java:253)
at
org.mule.transport.AbstractMessageReceiver.routeMessage(AbstractMessageReceiver.java:194)
at
org.mule.transport.http.HttpMessageReceiver$HttpWorker.doRequest(HttpMessageReceiver.java:272)
at
org.mule.transport.http.HttpMessageReceiver$HttpWorker.processRequest(HttpMessageReceiver.java:230)
at
org.mule.transport.http.HttpMessageReceiver$HttpWorker.run(HttpMessageReceiver.java:190)
at org.mule.work.WorkerContext.run(WorkerContext.java:310)
at
edu.emory.mathcs.backport.java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1061)
at
edu.emory.mathcs.backport.java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
at java.lang.Thread.run(Thread.java:619)

********************************************************************************

DEBUG 2009-02-16 09:23:44,868 [connector.http.0.receiver.2]
org.mule.DefaultMuleMessage: new copy of message for
Thread[connector.http.0.receiver.2,5,main]
DEBUG 2009-02-16 09:23:44,884 [connector.http.0.receiver.2]
org.mule.util.DefaultStreamCloserService: Unable to find an StreamCloser for
the stream type: class org.apache.activemq.command.ActiveMQObjectMessage,
the stream: ActiveMQObjectMessage {commandId = 0, responseRequired = false,
messageId = ID:W501-115D-49747-1234772453076-0:0:5:1:1, originalDestination
= null, originalTransactionId = null, producerId = null, destination =
queue://syncPrintJobs, transactionId = null, expiration = 0, timestamp =
1234772517190, arrival = 0, brokerInTime = 0, brokerOutTime = 0,
correlationId = e00549ff-fc02-11dd-a580-6b442b53c3c9, replyTo = null,
persistent = true, type = null, priority = 4, groupID = null, groupSequence
= 0, targetConsumerId = null, compressed = false, userID = null, content =
org.apache.activemq.util.ByteSequence@108beb8, marshalledProperties = null,
dataStructure = null, redeliveryCounter = 0, size = 0, properties =
{http_method=POST,
MULE_SESSION=SUQ9ZTAwYTJjMDAtZmMwMi0xMWRkLWE1ODAtNmI0NDJiNTNjM2M5,
http_request=/services/OnDemandPrintService,
serviceInterfaces=[be.xxx.mule.service.OnDemandPrintService],
MULE_ENCODING=UTF-8, http_version=HTTP/1.0,
MULE_CORRELATION_ID=e00549ff-fc02-11dd-a580-6b442b53c3c9,
serviceNamespace=http://w501-115d:81/services/OnDemandPrintService,
SOAPAction="", MULE_ENDPOINT=jms://syncPrintJobs,
axisOptions={wsdlServiceElement=RpcOnDemandPrintServiceEndpoint,
wsdlPortjType=OnDemandPrintService},
MULE_ORIGINATING_ENDPOINT=endpoint.http.w501.115d.81.services},
readOnlyProperties = false, readOnlyBody = false, droppable = false} will
not be closed.
WARN 2009-02-16 09:23:44,884 [connector.http.0.receiver.2]
org.mule.config.ExceptionHelper: Failed to load error mappings from:
META-INF/services/org/mule/config/axis-exception-mappings.properties This
may be because there are no error code mappings for protocol: axis
ERROR 2009-02-16 09:23:44,900 [connector.http.0.receiver.2]
org.mule.transport.soap.axis.AxisServiceComponent:
java.lang.reflect.UndeclaredThrowableException target service is:
OnDemandPrintService. MuleEvent is: MuleEvent:
dfd3648d-fc02-11dd-a580-6b442b53c3c9, sync=true, stop processing=false,
DefaultInboundEndpoint{endpointUri=http://w501-115d:81/services/OnDemandPrintService,
connector=HttpConnector{this=5017e9, started=true, initialised=true,
name='connector.http.0', disposed=false,
numberOfConcurrentTransactedReceivers=4,
createMultipleTransactedReceivers=true, connected=true,
supportedProtocols=[http], serviceOverrides=null}, transformer=[],
name='http:OnDemandPrintService', properties={},
transactionConfig=Transaction{factory=null, action=NEVER, timeout=0},
filter=null, deleteUnacceptedMessages=false, securityFilter=null,
synchronous=true, initialState=started, remoteSync=false,
remoteSyncTimeout=10000, endpointEncoding=UTF-8}
AxisFault
faultCode: {http://schemas.xmlsoap.org/soap/envelope/}Server.userException
faultSubcode:
faultString: java.lang.reflect.UndeclaredThrowableException
faultActor:
faultNode:
faultDetail:

{http://xml.apache.org/axis/}stackTrace:java.lang.reflect.UndeclaredThrowableException
at $Proxy0.print(Unknown Source)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at
org.apache.axis.providers.java.RPCProvider.invokeMethod(RPCProvider.java:397)
at
org.apache.axis.providers.java.RPCProvider.processMessage(RPCProvider.java:186)
at
org.apache.axis.providers.java.JavaProvider.invoke(JavaProvider.java:323)
at
org.mule.transport.soap.axis.extensions.MuleRPCProvider.invoke(MuleRPCProvider.java:99)
at
org.apache.axis.strategies.InvocationStrategy.visit(InvocationStrategy.java:32)
at org.apache.axis.SimpleChain.doVisiting(SimpleChain.java:118)
at org.apache.axis.SimpleChain.invoke(SimpleChain.java:83)
at org.apache.axis.handlers.soap.SOAPService.invoke(SOAPService.java:454)
at org.apache.axis.server.AxisServer.invoke(AxisServer.java:281)
at
org.mule.transport.soap.axis.AxisServiceComponent.doPost(AxisServiceComponent.java:580)
at
org.mule.transport.soap.axis.AxisServiceComponent.onCall(AxisServiceComponent.java:131)
at
org.mule.model.resolvers.CallableEntryPointResolver.invoke(CallableEntryPointResolver.java:52)
at
org.mule.model.resolvers.DefaultEntryPointResolverSet.invoke(DefaultEntryPointResolverSet.java:50)
at
org.mule.component.DefaultLifecycleAdapter.invoke(DefaultLifecycleAdapter.java:197)
at
org.mule.component.AbstractJavaComponent.invokeComponentInstance(AbstractJavaComponent.java:83)
at
org.mule.component.AbstractJavaComponent.doInvoke(AbstractJavaComponent.java:74)
at
org.mule.component.AbstractComponent.invokeInternal(AbstractComponent.java:125)
at org.mule.component.AbstractComponent.invoke(AbstractComponent.java:153)
at
org.mule.service.AbstractService.invokeComponent(AbstractService.java:933)
at org.mule.model.seda.SedaService.doSend(SedaService.java:234)
at org.mule.service.AbstractService.sendEvent(AbstractService.java:506)
at org.mule.DefaultMuleSession.sendEvent(DefaultMuleSession.java:354)
at
org.mule.routing.inbound.DefaultInboundRouterCollection.send(DefaultInboundRouterCollection.java:220)
at
org.mule.routing.inbound.DefaultInboundRouterCollection.route(DefaultInboundRouterCollection.java:180)
at
org.mule.transport.AbstractMessageReceiver$DefaultInternalMessageListener.onMessage(AbstractMessageReceiver.java:365)
at
org.mule.transport.AbstractMessageReceiver.routeMessage(AbstractMessageReceiver.java:253)
at
org.mule.transport.AbstractMessageReceiver.routeMessage(AbstractMessageReceiver.java:194)
at
org.mule.transport.http.HttpMessageReceiver$HttpWorker.doRequest(HttpMessageReceiver.java:272)
at
org.mule.transport.http.HttpMessageReceiver$HttpWorker.processRequest(HttpMessageReceiver.java:230)
at
org.mule.transport.http.HttpMessageReceiver$HttpWorker.run(HttpMessageReceiver.java:190)
at org.mule.work.WorkerContext.run(WorkerContext.java:310)
at
edu.emory.mathcs.backport.java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1061)
at
edu.emory.mathcs.backport.java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
at java.lang.Thread.run(Thread.java:619)
Caused by: org.mule.api.routing.ResponseTimeoutException: Response timed out
(100000ms) waiting for message response id
"e00549ff-fc02-11dd-a580-6b442b53c3c9" or this action was
interrupted.. Failed to route event via endpoint: null. Message payload is
of type: ActiveMQObjectMessage
at org.mule.routing.EventCorrelator.getResponse(EventCorrelator.java:471)
at org.mule.routing.EventCorrelator.getResponse(EventCorrelator.java:374)
at
org.mule.routing.response.AbstractResponseAggregator.getResponse(AbstractResponseAggregator.java:73)
at
org.mule.routing.response.DefaultResponseRouterCollection.getResponse(DefaultResponseRouterCollection.java:90)
at
org.mule.service.AbstractService.processAsyncReplyRouter(AbstractService.java:926)
at org.mule.model.seda.SedaService.doSend(SedaService.java:236)
at org.mule.service.AbstractService.sendEvent(AbstractService.java:506)
at org.mule.DefaultMuleSession.sendEvent(DefaultMuleSession.java:354)
at
org.mule.routing.inbound.DefaultInboundRouterCollection.send(DefaultInboundRouterCollection.java:220)
at
org.mule.routing.inbound.DefaultInboundRouterCollection.route(DefaultInboundRouterCollection.java:180)
at
org.mule.transport.AbstractMessageReceiver$DefaultInternalMessageListener.onMessage(AbstractMessageReceiver.java:365)
at
org.mule.transport.AbstractMessageReceiver.routeMessage(AbstractMessageReceiver.java:253)
at
org.mule.transport.AbstractMessageReceiver.routeMessage(AbstractMessageReceiver.java:176)
at
org.mule.transport.soap.axis.AxisServiceProxy$AxisServiceHandler.invoke(AxisServiceProxy.java:84)
... 39 more

{http://xml.apache.org/axis/}hostname:W501-115D

java.lang.reflect.UndeclaredThrowableException
at org.apache.axis.AxisFault.makeFault(AxisFault.java:101)
at
org.apache.axis.providers.java.JavaProvider.invoke(JavaProvider.java:333)
at
org.mule.transport.soap.axis.extensions.MuleRPCProvider.invoke(MuleRPCProvider.java:99)
at
org.apache.axis.strategies.InvocationStrategy.visit(InvocationStrategy.java:32)
at org.apache.axis.SimpleChain.doVisiting(SimpleChain.java:118)
at org.apache.axis.SimpleChain.invoke(SimpleChain.java:83)
at org.apache.axis.handlers.soap.SOAPService.invoke(SOAPService.java:454)
at org.apache.axis.server.AxisServer.invoke(AxisServer.java:281)
at
org.mule.transport.soap.axis.AxisServiceComponent.doPost(AxisServiceComponent.java:580)
at
org.mule.transport.soap.axis.AxisServiceComponent.onCall(AxisServiceComponent.java:131)
at
org.mule.model.resolvers.CallableEntryPointResolver.invoke(CallableEntryPointResolver.java:52)
at
org.mule.model.resolvers.DefaultEntryPointResolverSet.invoke(DefaultEntryPointResolverSet.java:50)
at
org.mule.component.DefaultLifecycleAdapter.invoke(DefaultLifecycleAdapter.java:197)
at
org.mule.component.AbstractJavaComponent.invokeComponentInstance(AbstractJavaComponent.java:83)
at
org.mule.component.AbstractJavaComponent.doInvoke(AbstractJavaComponent.java:74)
at
org.mule.component.AbstractComponent.invokeInternal(AbstractComponent.java:125)
at org.mule.component.AbstractComponent.invoke(AbstractComponent.java:153)
at
org.mule.service.AbstractService.invokeComponent(AbstractService.java:933)
at org.mule.model.seda.SedaService.doSend(SedaService.java:234)
at org.mule.service.AbstractService.sendEvent(AbstractService.java:506)
at org.mule.DefaultMuleSession.sendEvent(DefaultMuleSession.java:354)
at
org.mule.routing.inbound.DefaultInboundRouterCollection.send(DefaultInboundRouterCollection.java:220)
at
org.mule.routing.inbound.DefaultInboundRouterCollection.route(DefaultInboundRouterCollection.java:180)
at
org.mule.transport.AbstractMessageReceiver$DefaultInternalMessageListener.onMessage(AbstractMessageReceiver.java:365)
at
org.mule.transport.AbstractMessageReceiver.routeMessage(AbstractMessageReceiver.java:253)
at
org.mule.transport.AbstractMessageReceiver.routeMessage(AbstractMessageReceiver.java:194)
at
org.mule.transport.http.HttpMessageReceiver$HttpWorker.doRequest(HttpMessageReceiver.java:272)
at
org.mule.transport.http.HttpMessageReceiver$HttpWorker.processRequest(HttpMessageReceiver.java:230)
at
org.mule.transport.http.HttpMessageReceiver$HttpWorker.run(HttpMessageReceiver.java:190)
at org.mule.work.WorkerContext.run(WorkerContext.java:310)
at
edu.emory.mathcs.backport.java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1061)
at
edu.emory.mathcs.backport.java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
at java.lang.Thread.run(Thread.java:619)
Caused by: java.lang.reflect.UndeclaredThrowableException
at $Proxy0.print(Unknown Source)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at
org.apache.axis.providers.java.RPCProvider.invokeMethod(RPCProvider.java:397)
at
org.apache.axis.providers.java.RPCProvider.processMessage(RPCProvider.java:186)
at
org.apache.axis.providers.java.JavaProvider.invoke(JavaProvider.java:323)
... 31 more
Caused by: org.mule.api.routing.ResponseTimeoutException: Response timed out
(100000ms) waiting for message response id
"e00549ff-fc02-11dd-a580-6b442b53c3c9" or this action was interrupted..
Failed to route event via endpoint: null. Message payload is of type:
ActiveMQObjectMessage
at org.mule.routing.EventCorrelator.getResponse(EventCorrelator.java:471)
at org.mule.routing.EventCorrelator.getResponse(EventCorrelator.java:374)
at
org.mule.routing.response.AbstractResponseAggregator.getResponse(AbstractResponseAggregator.java:73)
at
org.mule.routing.response.DefaultResponseRouterCollection.getResponse(DefaultResponseRouterCollection.java:90)
at
org.mule.service.AbstractService.processAsyncReplyRouter(AbstractService.java:926)
at org.mule.model.seda.SedaService.doSend(SedaService.java:236)
at org.mule.service.AbstractService.sendEvent(AbstractService.java:506)
at org.mule.DefaultMuleSession.sendEvent(DefaultMuleSession.java:354)
at
org.mule.routing.inbound.DefaultInboundRouterCollection.send(DefaultInboundRouterCollection.java:220)
at
org.mule.routing.inbound.DefaultInboundRouterCollection.route(DefaultInboundRouterCollection.java:180)
at
org.mule.transport.AbstractMessageReceiver$DefaultInternalMessageListener.onMessage(AbstractMessageReceiver.java:365)
at
org.mule.transport.AbstractMessageReceiver.routeMessage(AbstractMessageReceiver.java:253)
at
org.mule.transport.AbstractMessageReceiver.routeMessage(AbstractMessageReceiver.java:176)
at
org.mule.transport.soap.axis.AxisServiceProxy$AxisServiceHandler.invoke(AxisServiceProxy.java:84)
... 39 more
DEBUG 2009-02-16 09:23:45,212 [connector.http.0.receiver.2]
org.mule.transport.soap.axis.AxisServiceComponent: Returned
Content-Type:text/xml; charset=utf-8
DEBUG 2009-02-16 09:23:45,227 [connector.http.0.receiver.2]
org.mule.transport.soap.axis.AxisServiceComponent: Response sent.
DEBUG 2009-02-16 09:23:45,227 [connector.http.0.receiver.2]
org.mule.model.seda.SedaService: Outbound router on service
'_axisServiceaxisConnector' doesn't have any endpoints configured.
DEBUG 2009-02-16 09:23:45,227 [connector.http.0.receiver.2]
org.mule.model.seda.SedaService: Waiting for response router message
DEBUG 2009-02-16 09:23:45,227 [connector.http.0.receiver.2]
org.mule.routing.response.DefaultResponseRouterCollection: There are no
routers configured on the response router. Returning the current message
DEBUG 2009-02-16 09:23:45,227 [connector.http.0.receiver.2]
org.mule.transport.http.transformers.MuleMessageToHttpResponse: Applying
transformer MuleMessageToHttpResponse
(org.mule.transport.http.transformers.MuleMessageToHttpResponse)
DEBUG 2009-02-16 09:23:45,243 [connector.http.0.receiver.2]
org.mule.transport.http.transformers.MuleMessageToHttpResponse: Object
before transform:
org.mule.transport.WriterMessageAdapter/org.mule.transport.WriterMessageAdapter@6116f2{id=dfd5ae7e-fc02-11dd-a580-6b442b53c3c9,
payload=java.lang.String, correlationId=null, correlationGroup=-1, corre...
DEBUG 2009-02-16 09:23:45,243 [connector.http.0.receiver.2]
org.mule.transport.http.transformers.MuleMessageToHttpResponse: Object after
transform: org.mule.transport.http.HttpResponse@221d9a
DEBUG 2009-02-16 09:23:45,243 [connector.http.0.receiver.2]
org.mule.transport.http.transformers.MuleMessageToHttpResponse: The
transformed object is of expected type. Type is: HttpResponse
DEBUG 2009-02-16 09:23:45,258 [connector.http.0.receiver.2]
org.mule.transport.http.HttpMessageReceiver: Closing HTTP connection.
DEBUG 2009-02-16 09:23:45,258 [connector.http.0.receiver.2]
org.mule.transport.http.HttpServerConnection: Closing:
Socket[addr=/10.35.205.1,port=49766,localport=81]
DEBUG 2009-02-16 09:23:45,258 [connector.http.0.receiver.2]
org.mule.util.monitor.ExpiryMonitor: Removing expirable:
org.mule.transport.http.HttpMessageReceiver$HttpWorker@d246ce
DEBUG 2009-02-16 09:23:53,975 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:23:54,115 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000
DEBUG 2009-02-16 09:24:03,990 [OnDemandPrintService.1]
org.mule.model.seda.SedaService: Service OnDemandPrintService polling queue
OnDemandPrintService.service, timeout = 10000
DEBUG 2009-02-16 09:24:04,130 [_axisServiceaxisConnector.1]
org.mule.model.seda.SedaService: Service _axisServiceaxisConnector polling
queue _axisServiceaxisConnector.service, timeout = 10000

--
View this message in context: http://www.nabble.com/request-response-using-axis-and-async-reply-tp22033264p22033264.html
Sent from the Mule - User mailing list archive at Nabble.com.

---------------------------------------------------------------------
To unsubscribe from this list, please visit:

http://xircles.codehaus.org/manage_email

Antoine Borg

unread,

Feb 17, 2009, 3:26:11 AM2/17/09

to us...@mule.codehaus.org

Hi,

In your outbound section, you need to specify the <reply-to> element so that
messages that are responses are correctly sent to the async-reply router.

Antoine Borg, Senior Consultant | Tel: +32 28 504 696
ricston Ltd., BP 2, 1180 Uccle, Brussels, BELGIUM
email: antoin...@ricston.com | blog: blog.ricston.com | web: ricston.com

this is my configuration:

****************************************************************************
****

(ThreadPoolExecutor.java:575)
at java.lang.Thread.run(Thread.java:619)
Caused by: org.mule.api.routing.ResponseTimeoutException: Response timed out
(100000ms) waiting for message response id
"e00549ff-fc02-11dd-a580-6b442b53c3c9" or this action was
interrupted.. Failed to route event via endpoint: null. Message payload is
of type: ActiveMQObjectMessage
at
org.mule.routing.EventCorrelator.getResponse(EventCorrelator.java:471)
at
org.mule.routing.EventCorrelator.getResponse(EventCorrelator.java:374)
at
org.mule.routing.response.AbstractResponseAggregator.getResponse(AbstractRes
ponseAggregator.java:73)
at
org.mule.routing.response.DefaultResponseRouterCollection.getResponse(Defaul
tResponseRouterCollection.java:90)
at
org.mule.service.AbstractService.processAsyncReplyRouter(AbstractService.jav

a:926)
at org.mule.model.seda.SedaService.doSend(SedaService.java:236)
at
org.mule.service.AbstractService.sendEvent(AbstractService.java:506)
at
org.mule.DefaultMuleSession.sendEvent(DefaultMuleSession.java:354)
at
org.mule.routing.inbound.DefaultInboundRouterCollection.send(DefaultInboundR
outerCollection.java:220)
at
org.mule.routing.inbound.DefaultInboundRouterCollection.route(DefaultInbound
RouterCollection.java:180)
at
org.mule.transport.AbstractMessageReceiver$DefaultInternalMessageListener.on
Message(AbstractMessageReceiver.java:365)
at
org.mule.transport.AbstractMessageReceiver.routeMessage(AbstractMessageRecei
ver.java:253)
at
org.mule.transport.AbstractMessageReceiver.routeMessage(AbstractMessageRecei
ver.java:176)
at
org.mule.transport.soap.axis.AxisServiceProxy$AxisServiceHandler.invoke(Axis

ServiceProxy.java:84)
... 39 more

{http://xml.apache.org/axis/}hostname:W501-115D

(ThreadPoolExecutor.java:575)
at java.lang.Thread.run(Thread.java:619)
Caused by: java.lang.reflect.UndeclaredThrowableException
at $Proxy0.print(Unknown Source)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39
)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl

.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at
org.apache.axis.providers.java.RPCProvider.invokeMethod(RPCProvider.java:397
)
at
org.apache.axis.providers.java.RPCProvider.processMessage(RPCProvider.java:1
86)

at
org.apache.axis.providers.java.JavaProvider.invoke(JavaProvider.java:323)
... 31 more
Caused by: org.mule.api.routing.ResponseTimeoutException: Response timed out
(100000ms) waiting for message response id
"e00549ff-fc02-11dd-a580-6b442b53c3c9" or this action was interrupted..
Failed to route event via endpoint: null. Message payload is of type:
ActiveMQObjectMessage
at
org.mule.routing.EventCorrelator.getResponse(EventCorrelator.java:471)
at
org.mule.routing.EventCorrelator.getResponse(EventCorrelator.java:374)
at
org.mule.routing.response.AbstractResponseAggregator.getResponse(AbstractRes
ponseAggregator.java:73)
at
org.mule.routing.response.DefaultResponseRouterCollection.getResponse(Defaul
tResponseRouterCollection.java:90)
at
org.mule.service.AbstractService.processAsyncReplyRouter(AbstractService.jav

ServiceProxy.java:84)
... 39 more
DEBUG 2009-02-16 09:23:45,040 [connector.http.0.receiver.2]
org.mule.transport.soap.axis.AxisServiceComponent: AxisFault:
AxisFault
faultCode: {http://schemas.xmlsoap.org/soap/envelope/}Server.userException
faultSubcode:
faultString: java.lang.reflect.UndeclaredThrowableException
faultActor:
faultNode:
faultDetail:

{http://xml.apache.org/axis/}stackTrace:java.lang.reflect.UndeclaredThrowabl
eException

ServiceProxy.java:84)
... 39 more

{http://xml.apache.org/axis/}hostname:W501-115D

Cuball

unread,

Feb 17, 2009, 3:54:33 AM2/17/09

to us...@mule.codehaus.org

I tried using the reply-to element but that didn't help. What address should
I give the reply-to element? The originating axis endpoint ?

below is my working config for Mule 1.4.3, migrating to Mule is really not
that easy :(

<mule-descriptor name="OnDemandPrintService"
implementation="be.xxx.mule.component.OnDemandPrintComponent">
<inbound-router>
<endpoint address="axis:http://s501-039:81/services"
connector="axisConnector" synchronous="true" />
</inbound-router>

<outbound-router matchAll="true">

<router className="org.mule.routing.outbound.MulticastingRouter" >
<endpoint address="jms://syncPrintJobs" connector="jmsConnector"
transformers="RemotePrintJobToPrintJob ObjectToJMSMessage"
synchronous="true"/>
</router>

</outbound-router>

<response-router timeout="100000">
<endpoint address="jms://scripturaResult" connector="jmsConnector"
transformers="JMSMessageToPrintJobResult"/>
<router
className="be.xxx.mule.routing.response.PrintJobResultCorrelationAggregator"
/>
</response-router>

<properties>
<list name="serviceInterfaces">
<entry value="be.xxx.mule.service.OnDemandPrintService"/>
</list>
<property name="serviceNamespace"
value="http://s501-039:81/services/OnDemandPrintService" />
<map name="axisOptions">
<property name="wsdlServiceElement"
value="RpcOnDemandPrintServiceEndpoint"/>
<property name="wsdlPortType" value="OnDemandPrintService"/>
</map>

</properties>

</mule-descriptor>

--
View this message in context: http://www.nabble.com/request-response-using-axis-and-async-reply-tp22033264p22053050.html

Cuball

unread,

Feb 17, 2009, 4:57:38 AM2/17/09

to us...@mule.codehaus.org

What I don't understand is that my async-reply router receives the message,
but it's like it's not dispatching back to the calling webservice thread.
If I look at the logs I see that there is no Correlation Group Size set.
Can this be the problem ?

DEBUG 2009-02-17 10:52:56,986 [ActiveMQ Session Task]
be.oz.mule.transformer.JMSMessageToPrintJobResult: Applying transformer
JMSMessageToPrintJobResult
(be.oz.mule.transformer.JMSMessageToPrintJobResult)
DEBUG 2009-02-17 10:52:56,989 [ActiveMQ Session Task]
be.oz.mule.transformer.JMSMessageToPrintJobResult: Object before transform:

ActiveMQObjectMessage {commandId = 5, responseRequired = true, messageId =

ID:W501-115D-52808-1234855812072-0:13:1:1:1, originalDestination = null,
originalTransactionId = null, producerId = ID:W501-1...
DEBUG 2009-02-17 10:52:56,989 [ActiveMQ Session Task]
be.oz.mule.transformer.JMSMessageToPrintJobResult: Object after transform:
be.oz.mule.domain.remote.PrintJobResult@171bc3f
DEBUG 2009-02-17 10:52:56,989 [ActiveMQ Session Task]
be.oz.mule.transformer.JMSMessageToPrintJobResult: The transformed object is

of expected type. Type is: PrintJobResult

DEBUG 2009-02-17 10:52:56,991 [ActiveMQ Session Task]

org.mule.routing.EventCorrelator: Adding event to response aggregator group:

ID:W501-115D-52808-1234855812072-0:13:1:1:1
WARN 2009-02-17 10:52:56,991 [ActiveMQ Session Task]

org.mule.routing.CollectionCorrelatorCallback: Correlation Group Size not
set, but CorrelationAggregator is being used. Message is being forwarded

DEBUG 2009-02-17 10:52:56,992 [ActiveMQ Session Task]
org.mule.routing.EventCorrelator: Creating latch for
ID:W501-115D-52808-1234855812072-0:13:1:1:1 in
org.mule.routing.EventCorrelator@19fa8f7

--
View this message in context: http://www.nabble.com/request-response-using-axis-and-async-reply-tp22033264p22054003.html

Antoine Borg

unread,

Mar 6, 2009, 3:53:57 AM3/6/09

to us...@mule.codehaus.org

Hi,

The <reply-to> should point to the address for the response router, so going
by your 1.x config, this should be jms://scripturaResult