|
View:
New views
7 Messages
—
Rating Filter:
Alert me
|
|
|
Is there a way to start Mule Server without starting the service?When Mule server start, it always open every inbound endpoint for each service. Is there a way to start the inbound endpoint later or at a customized time?
|
|
|
Re: Is there a way to start Mule Server without starting the service?Hi,
Yes of course. You can specify that when mule starts services should be "stopped" or "paused" or "started" (default). A services inbound endpoints are only started and poll/listen for message when the service is started. See: http://mule.mulesource.org/display/MULE2USER/Service+Configuration+Reference You can start/stop/pause services either programatically or via the JMX interface. Note pausing a service will not affect endpoints, just if the inbound messages are processed or not. Dan On Jul 4, 2008, at 10:15 PM, youhaodeyi wrote: > > When Mule server start, it always open every inbound endpoint for each > service. Is there a way to start the inbound endpoint later or at a > customized time? > -- > View this message in context: http://www.nabble.com/Is-there-a-way-to-start-Mule-Server-without-starting-the-service--tp18288113p18288113.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 > > --------------------------------------------------------------------- To unsubscribe from this list, please visit: http://xircles.codehaus.org/manage_email |
|
|
Re: Is there a way to start Mule Server without starting the service?yes, you are right. thanks.
|
|
|
JBPM process not being savedHi
I'm using Mule 2.0.1, an am picking up the error that my process state isn't being saved into the database. I am using the SendMuleEventAndContinue method and expect the process state to be saved after the event is sent. I've attached a log snippet + my config at the end. Process with ID 9830400 is not being saved, hence the error. Regards Kiren Pillay Tau Space South Africa org.mule.model.resolvers.MethodHeaderPropertyEntryPointResolver: Invoking za.co.neotel.provision2.mule.ServiceOrderStripper.getOrderParameters({class java.lang.String}) ***********<ServiceOrder><OrderId>26</OrderId><Type>NewSubscriber</Type><IMSI>654530200232311</IMSI><CDN>27110200064</CDN><ESN>1r54t8i9</ESN><Status>PE</Status><OrderParameters><General><FirstName>shalendran</FirstName><MiddleName>s</MiddleName><Surname>shalen</Surname><IdNo>5706120109183</IdNo><KI>6551232560230110</KI><PackageName>Neo Connect Unlimited</PackageName><Template></Template></General><Voice><Service name="voice">Neo Connect Unlimited</Service></Voice><Email><Service><EmailAddress>0110200064@...</EmailAddress><Password>12061957</Password><MailPlan>NeomailExpertMail</MailPlan></Service></Email></OrderParameters></ServiceOrder>*************** xpath26 DEBUG 2008-07-07 00:45:39,586 [jdbcConnector.receiver.2] org.mule.model.resolvers.MethodHeaderPropertyEntryPointResolver: Result of call za.co.neotel.provision2.mule.ServiceOrderStripper.getOrderParameters({class java.lang.String}) is not null DEBUG 2008-07-07 00:45:39,588 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Applying transformer TransformerTemplate (org.mule.transformer.TransformerTemplate) DEBUG 2008-07-07 00:45:39,589 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object before transform: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@4da42824{id=41b7c1bd-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=null, correlationGroup=-1, c... DEBUG 2008-07-07 00:45:39,589 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object after transform: za.co.neotel.provision2.messages.ServiceOrder@54477b4e DEBUG 2008-07-07 00:45:39,589 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: The transformed object is of expected type. Type is: ServiceOrder DEBUG 2008-07-07 00:45:39,589 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: No CorrelationId is set on the message, will set a new Id DEBUG 2008-07-07 00:45:39,589 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Extracted correlation Id as: 41b7c1bd-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Setting Correlation info on Outbound router for endpoint: vm://customer.requests Id=41b7c1bd-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Message being sent to: vm://customer.requests DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@4da42824{id=41b7c1bd-4bad-11dd-89e9-25c00949723f, payload=za.co.neotel.provision2.messages.ServiceOrder, correlationId=41b7c1bd-4bad-11dd-89e9-25c00949723f, correlationGroup=-1, correlationSeq=-1, encoding=UTF-8, exceptionPayload=null, properties=Properites{invocation:{}, inbound:{}, outbound:{MULE_ORIGINATING_ENDPOINT=getServiceOrders, MULE_CORRELATION_ID=41b7c1bd-4bad-11dd-89e9-25c00949723f}, session:{}, }} DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: MuleSession has received synchronous event on endpoint: DefaultOutboundEndpoint{endpointUri=vm://customer.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderRequests', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Creating event with data: za.co.neotel.provision2.messages.ServiceOrder, for endpoint: DefaultOutboundEndpoint{endpointUri=vm://customer.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderRequests', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event: MuleEvent: 41c5f290-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultOutboundEndpoint{endpointUri=vm://customer.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderRequests', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,614 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:45:39,614 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,618 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:39,618 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:39,618 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowing a dispatcher for endpoint: vm://customer.requests DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed a dispatcher for endpoint: vm://customer.requests = VMMessageDispatcher{this=71b456f, endpoint=vm://customer.requests, disposed=false} DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed dispatcher: VMMessageDispatcher{this=71b456f, endpoint=vm://customer.requests, disposed=false} DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connecting: VMMessageDispatcher{this=71b456f, endpoint=vm://customer.requests, disposed=false} INFO 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connected: VMMessageDispatcher{this=71b456f, endpoint=vm://customer.requests, disposed=false} DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Looking up vm receiver for address: vm://customer.requests DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Found exact receiver match on endpointUri: vm://customer.requests DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageReceiver: Message Received from: vm://customer.requests DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Applying transformer Base64Decoder (org.mule.transformer.codec.Base64Decoder) DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object before transform: SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object after transform: {73,68,61,52,49,98,56,56,53,48,101,45,52,98,97,100,45,49,49,100,100,45,56,57,101,57,45,50,53,99,48,48,57,52,57,55,50,51,102} DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: The transformed object is of expected type. Type is: byte[] DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Parsing session header: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Added MuleSession variable: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Got session with id: 41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event to service: ToBPMS event is: MuleEvent: 41cb70d2-4bad-11dd-89e9-25c00949723f, sync=true, stop processing=false, DefaultInboundEndpoint{endpointUri=vm://customer.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderRequests', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: Service: ToBPMS has rec*eived synchronous event on: vm://customer.requests DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: ToBPMS : got proxy for 41cb70d2-4bad-11dd-89e9-25c00949723f = org.mule.component.DefaultJavaComponent component for: ToBPMS DEBUG 2008-07-07 00:45:39,627 [jdbcConnector.receiver.2] org.mule.registry.TransientRegistry: lookupObjects: type=interface org.mule.api.transformer.Transformer scope=2 DEBUG 2008-07-07 00:45:39,627 [jdbcConnector.receiver.2] org.mule.config.spring.SpringRegistry: lookupObjects: type=interface org.mule.api.transformer.Transformer scope=2 DEBUG 2008-07-07 00:45:39,635 [jdbcConnector.receiver.2] org.mule.transformer.simple.ObjectToString: java.lang.Object has been added as source type for this transformer, there will be no source type checking performed DEBUG 2008-07-07 00:45:39,637 [jdbcConnector.receiver.2] org.mule.transformer.simple.ObjectToString: Applying transformer ObjectToString (org.mule.transformer.simple.ObjectToString) DEBUG 2008-07-07 00:45:39,637 [jdbcConnector.receiver.2] org.mule.transformer.simple.ObjectToString: Object before transform: za.co.neotel.provision2.messages.ServiceOrder@54477b4e DEBUG 2008-07-07 00:45:39,637 [jdbcConnector.receiver.2] org.mule.transformer.simple.ObjectToString: Object after transform: za.co.neotel.provision2.messages.ServiceOrder@54477b4e DEBUG 2008-07-07 00:45:39,637 [jdbcConnector.receiver.2] org.mule.transformer.simple.ObjectToString: The transformed object is of expected type. Type is: String INFO 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.component.simple.LogComponent: ******************************************************************************** * Message received in service: ToBPMS. Content is: * * 'za.co.neotel.provision2.messages.ServiceOrder@54477b4e' * ******************************************************************************** DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Applying transformer TransformerTemplate (org.mule.transformer.TransformerTemplate) DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object before transform: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@15136019{id=41b7c1bd-4bad-11dd-89e9-25c00949723f, payload=za.co.neotel.provision2.messages.ServiceOrder, correlationId... DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object after transform: za.co.neotel.provision2.messages.ServiceOrder@54477b4e DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: The transformed object is of expected type. Type is: ServiceOrder DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: CorrelationId is already set to '41b7c1bd-4bad-11dd-89e9-25c00949723f' , not setting it again DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Message being sent to: bpm://getsubinfo DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@15136019{id=41b7c1bd-4bad-11dd-89e9-25c00949723f, payload=za.co.neotel.provision2.messages.ServiceOrder, correlationId=41b7c1bd-4bad-11dd-89e9-25c00949723f, correlationGroup=-1, correlationSeq=-1, encoding=UTF-8, exceptionPayload=null, properties=Properites{invocation:{}, inbound:{}, outbound:{MULE_ORIGINATING_ENDPOINT=ServiceOrderRequests, MULE_CORRELATION_ID=41b7c1bd-4bad-11dd-89e9-25c00949723f, MULE_ENDPOINT=vm://customer.requests}, session:{}, }} DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: MuleSession has received synchronous event on endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Creating event with data: za.co.neotel.provision2.messages.ServiceOrder, for endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event: MuleEvent: 41cd4593-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding property to session header: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=41b8850e-4bad-11dd-89e9-25c00949723f;ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=41b8850e-4bad-11dd-89e9-25c00949723f;ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQxYjg4NTBlLTRiYWQtMTFkZC04OWU5LTI1YzAwOTQ5NzIzZg== DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowing a dispatcher for endpoint: bpm://getsubinfo DEBUG 2008-07-07 00:45:39,640 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowed a dispatcher for endpoint: bpm://getsubinfo = ProcessMessageDispatcher{this=22aed3a5, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:45:39,640 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowed dispatcher: ProcessMessageDispatcher{this=22aed3a5, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:45:39,640 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Connecting: ProcessMessageDispatcher{this=22aed3a5, endpoint=bpm://getsubinfo, disposed=false} INFO 2008-07-07 00:45:39,641 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Connected: ProcessMessageDispatcher{this=22aed3a5, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:45:39,680 [jdbcConnector.receiver.2] org.jbpm.configuration.JbpmContextInfo: creating jbpm context with service factories '[tx, message, scheduler, logging, persistence, authentication]' DEBUG 2008-07-07 00:45:39,681 [jdbcConnector.receiver.2] org.jbpm.JbpmContext: creating org.jbpm.JbpmContext@782bbb7b DEBUG 2008-07-07 00:45:39,681 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceServiceFactory: creating persistence service DEBUG 2008-07-07 00:45:39,681 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: creating hibernate session DEBUG 2008-07-07 00:45:39,681 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: beginning hibernate transaction DEBUG 2008-07-07 00:45:39,683 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: begun hibernate transaction org.hibernate.transaction.JDBCTransaction@17cb0a16 WARN 2008-07-07 00:45:39,691 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: SQL Warning: 10000, SQLState: 01J01 WARN 2008-07-07 00:45:39,691 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: Database 'muleEmbeddedDB' not created, connection made to existing database instead. WARN 2008-07-07 00:45:39,693 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: SQL Warning: 10000, SQLState: 01J01 WARN 2008-07-07 00:45:39,693 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: Database 'muleEmbeddedDB' not created, connection made to existing database instead. DEBUG 2008-07-07 00:45:39,706 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'process-start' on 'ProcessDefinition(getsubinfo)' for 'Token(/)' DEBUG 2008-07-07 00:45:39,739 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'incomingSource' in 'TokenVariableMap7ea06d25' with value 'ServiceOrderRequests' DEBUG 2008-07-07 00:45:39,749 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'D', 'org.jbpm.context.exe.converter.DoubleToStringConverter' DEBUG 2008-07-07 00:45:39,749 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'C', 'org.jbpm.context.exe.converter.CharacterToStringConverter' DEBUG 2008-07-07 00:45:39,750 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'B', 'org.jbpm.context.exe.converter.BooleanToStringConverter' DEBUG 2008-07-07 00:45:39,754 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'Y', 'org.jbpm.context.exe.converter.BytesToByteArrayConverter' DEBUG 2008-07-07 00:45:39,755 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'A', 'org.jbpm.context.exe.converter.DateToLongConverter' DEBUG 2008-07-07 00:45:39,756 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'R', 'org.jbpm.context.exe.converter.SerializableToByteArrayConverter' DEBUG 2008-07-07 00:45:39,757 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'I', 'org.jbpm.context.exe.converter.IntegerToLongConverter' DEBUG 2008-07-07 00:45:39,757 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'H', 'org.jbpm.context.exe.converter.ShortToLongConverter' DEBUG 2008-07-07 00:45:39,758 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'G', 'org.jbpm.context.exe.converter.FloatToDoubleConverter' DEBUG 2008-07-07 00:45:39,759 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'F', 'org.jbpm.context.exe.converter.FloatToStringConverter' DEBUG 2008-07-07 00:45:39,759 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'E', 'org.jbpm.context.exe.converter.ByteToLongConverter' DEBUG 2008-07-07 00:45:39,820 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'MULE_SESSION' in 'TokenVariableMap7ea06d25' with value 'SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQxYjg4NTBlLTRiYWQtMTFkZC04OWU5LTI1YzAwOTQ5NzIzZg==' DEBUG 2008-07-07 00:45:39,820 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'MULE_CORRELATION_ID' in 'TokenVariableMap7ea06d25' with value '41b7c1bd-4bad-11dd-89e9-25c00949723f' DEBUG 2008-07-07 00:45:39,820 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'MULE_ENDPOINT' in 'TokenVariableMap7ea06d25' with value 'bpm://getsubinfo' DEBUG 2008-07-07 00:45:39,820 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'incoming' in 'TokenVariableMap7ea06d25' with value 'za.co.neotel.provision2.messages.ServiceOrder@54477b4e' DEBUG 2008-07-07 00:45:39,840 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'MULE_ORIGINATING_ENDPOINT' in 'TokenVariableMap7ea06d25' with value 'ServiceOrderRequests' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'incomingSource' in 'TokenVariableMap7ea06d25' to value 'ServiceOrderRequests' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'MULE_SESSION' in 'TokenVariableMap7ea06d25' to value 'SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQxYjg4NTBlLTRiYWQtMTFkZC04OWU5LTI1YzAwOTQ5NzIzZg==' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'MULE_CORRELATION_ID' in 'TokenVariableMap7ea06d25' to value '41b7c1bd-4bad-11dd-89e9-25c00949723f' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'MULE_ENDPOINT' in 'TokenVariableMap7ea06d25' to value 'bpm://getsubinfo' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'incoming' in 'TokenVariableMap7ea06d25' to value 'za.co.neotel.provision2.messages.ServiceOrder@54477b4e' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'MULE_ORIGINATING_ENDPOINT' in 'TokenVariableMap7ea06d25' to value 'ServiceOrderRequests' DEBUG 2008-07-07 00:45:39,868 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'before-signal' on 'StartState(recvRequest)' for 'Token(/)' DEBUG 2008-07-07 00:45:39,893 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'node-leave' on 'StartState(recvRequest)' for 'Token(/)' DEBUG 2008-07-07 00:45:39,894 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'transition' on 'Transition(1af2f973)' for 'Token(/)' DEBUG 2008-07-07 00:45:39,964 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'node-enter' on 'State(receiveRequest)' for 'Token(/)' DEBUG 2008-07-07 00:45:40,036 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: executing action 'Action(21533b2c)' DEBUG 2008-07-07 00:45:40,036 [jdbcConnector.receiver.2] org.jbpm.graph.exe.Token: token[9863168] is locked by token[9863168] DEBUG 2008-07-07 00:45:40,074 [jdbcConnector.receiver.2] org.mule.transport.bpm.jbpm.actions.ValidateMessageSource: Executing action org.mule.transport.bpm.jbpm.actions.ValidateMessageSource from state "receiveRequest" DEBUG 2008-07-07 00:45:40,074 [jdbcConnector.receiver.2] org.mule.transport.bpm.jbpm.actions.ValidateMessageSource: Validating message source = ServiceOrderRequests, expected = ServiceOrderRequests DEBUG 2008-07-07 00:45:40,074 [jdbcConnector.receiver.2] org.jbpm.graph.exe.Token: token[9863168] is unlocked by token[9863168] DEBUG 2008-07-07 00:45:40,075 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: executing action 'Action(5f51d6cb)' DEBUG 2008-07-07 00:45:40,075 [jdbcConnector.receiver.2] org.jbpm.graph.exe.Token: token[9863168] is locked by token[9863168] DEBUG 2008-07-07 00:45:40,079 [jdbcConnector.receiver.2] org.mule.transport.bpm.jbpm.actions.StoreIncomingData: Executing action org.mule.transport.bpm.jbpm.actions.StoreIncomingData from state "receiveRequest" DEBUG 2008-07-07 00:45:40,088 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'recvRequest' in 'TokenVariableMap7ea06d25' with value 'za.co.neotel.provision2.messages.ServiceOrder@54477b4e' DEBUG 2008-07-07 00:45:40,088 [jdbcConnector.receiver.2] org.jbpm.graph.exe.Token: token[9863168] is unlocked by token[9863168] DEBUG 2008-07-07 00:45:40,088 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: executing action 'Action(75ecda50)' DEBUG 2008-07-07 00:45:40,088 [jdbcConnector.receiver.2] org.jbpm.graph.exe.Token: token[9863168] is locked by token[9863168] DEBUG 2008-07-07 00:45:40,092 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'node-leave' on 'State(receiveRequest)' for 'Token(/)' DEBUG 2008-07-07 00:45:40,092 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'transition' on 'Transition(18170f98)' for 'Token(/)' DEBUG 2008-07-07 00:45:40,094 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'node-enter' on 'State(sendMessage)' for 'Token(/)' DEBUG 2008-07-07 00:45:40,128 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: executing action 'action[sendRequest]' DEBUG 2008-07-07 00:45:40,133 [jdbcConnector.receiver.2] org.mule.transport.bpm.jbpm.actions.SendMuleEventAndContinue: Executing action org.mule.transport.bpm.jbpm.actions.SendMuleEventAndContinue from state "sendMessage" DEBUG 2008-07-07 00:45:40,254 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageReceiver: Executing process is sending an event (synchronously) to Mule endpoint = HLRSyncSvc DEBUG 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageReceiver: Message Received from: bpm://getsubinfo DEBUG 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: There is no session id on the request using key: ID. Generating new session id: 422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event to service: FromBPMS event is: MuleEvent: 422b6b26-4bad-11dd-89e9-25c00949723f, sync=true, stop processing=false, DefaultInboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='ProcessEngine', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: Service: FromBPMS has received synchronous event on: bpm://getsubinfo DEBUG 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: FromBPMS : got proxy for 422b6b26-4bad-11dd-89e9-25c00949723f = org.mule.component.DefaultJavaComponent component for: FromBPMS INFO 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.component.simple.LogComponent: ******************************************************************************** * Message received in service: FromBPMS. Content is: '26' * ******************************************************************************** DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Applying transformer TransformerTemplate (org.mule.transformer.TransformerTemplate) DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object before transform: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@6bfe3238{id=422b6b24-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=9830400, correlationGroup=-1... DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object after transform: 26 DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: CorrelationId is already set to '9830400' , not setting it again DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Message being sent to: vm://hlr.requests DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@6bfe3238{id=422b6b24-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=9830400, correlationGroup=-1, correlationSeq=-1, encoding=UTF-8, exceptionPayload=null, properties=Properites{invocation:{}, inbound:{}, outbound:{MULE_BPM_STARTED=Mon Jul 07 00:45:39 SAST 2008, MULE_ORIGINATING_ENDPOINT=ProcessEngine, MULE_BPM_PROCESS_TYPE=getsubinfo, MULE_BPM_PROCESS_ID=9830400, MULE_CORRELATION_ID=9830400, MULE_BPM_ENDPOINT=HLRSyncSvc}, session:{}, }} DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: MuleSession has received synchronous event on endpoint: DefaultOutboundEndpoint{endpointUri=vm://hlr.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='HLRSyncSvc', properties={method=getInfo}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Creating event with data: java.lang.String, for endpoint: DefaultOutboundEndpoint{endpointUri=vm://hlr.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='HLRSyncSvc', properties={method=getInfo}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event: MuleEvent: 422b9237-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultOutboundEndpoint{endpointUri=vm://hlr.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='HLRSyncSvc', properties={method=getInfo}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowing a dispatcher for endpoint: vm://hlr.requests DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed a dispatcher for endpoint: vm://hlr.requests = VMMessageDispatcher{this=7d9331eb, endpoint=vm://hlr.requests, disposed=false} DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed dispatcher: VMMessageDispatcher{this=7d9331eb, endpoint=vm://hlr.requests, disposed=false} DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connecting: VMMessageDispatcher{this=7d9331eb, endpoint=vm://hlr.requests, disposed=false} INFO 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connected: VMMessageDispatcher{this=7d9331eb, endpoint=vm://hlr.requests, disposed=false} DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Looking up vm receiver for address: vm://hlr.requests DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Found exact receiver match on endpointUri: vm://hlr.requests DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageReceiver: Message Received from: vm://hlr.requests DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Applying transformer Base64Decoder (org.mule.transformer.codec.Base64Decoder) DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object before transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object after transform: {73,68,61,52,50,50,98,54,98,50,53,45,52,98,97,100,45,49,49,100,100,45,56,57,101,57,45,50,53,99,48,48,57,52,57,55,50,51,102} DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: The transformed object is of expected type. Type is: byte[] DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Parsing session header: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Added MuleSession variable: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Got session with id: 422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event to service: HLRService event is: MuleEvent: 422be059-4bad-11dd-89e9-25c00949723f, sync=true, stop processing=false, DefaultInboundEndpoint{endpointUri=vm://hlr.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='HLRSyncSvc', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: Service: HLRService has received synchronous event on: vm://hlr.requests DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: HLRService : got proxy for 422be059-4bad-11dd-89e9-25c00949723f = org.mule.component.DefaultJavaComponent component for: HLRService INFO 2008-07-07 00:45:40,261 [jdbcConnector.receiver.2] za.co.neotel.provision2.common.Settings: Initialising settings DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.transport.tcp.TcpMessageReceiver: Message Received from: tcp://localhost:7777 DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.DefaultMuleSession: There is no session id on the request using key: ID. Generating new session id: 422f14ab-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.DefaultMuleSession: dispatching event to service: HLRTestServer, event is: MuleEvent: 422f14ac-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultInboundEndpoint{endpointUri=tcp://localhost:7777, connector=TcpConnector{this=7f371a59, started=true, initialised=true, name='TCPConn', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[tcp], serviceOverrides=null}, transformer=[], name='SocketToHLR', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.model.seda.SedaService: Service: HLRTestServer has received asynchronous event on: tcp://localhost:7777 DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.model.seda.SedaService: Service: HLRTestServer has received asynchronous event on: tcp://localhost:7777 DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.registry.TransientRegistry: lookupObject: key=_muleQueueManager scope=2 DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.registry.TransientRegistry: Failed to find object in Registry ID: org.mule.Registry.Transient DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.config.spring.SpringRegistry: lookupObject: key=_muleQueueManager scope=2 DEBUG 2008-07-07 00:45:40,280 [TCPConn.receiver.2] org.mule.model.seda.SedaService: Service HLRTestServer putting event on queue HLRTestServer: MuleEvent: 422f14ac-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultInboundEndpoint{endpointUri=tcp://localhost:7777, connector=TcpConnector{this=7f371a59, started=true, initialised=true, name='TCPConn', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[tcp], serviceOverrides=null}, transformer=[], name='SocketToHLR', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,280 [HLRTestServer.1] org.mule.model.seda.SedaService: Service: HLRTestServer dequeued event on: tcp://localhost:7777 DEBUG 2008-07-07 00:45:40,281 [HLRTestServer.2] org.mule.DefaultMuleMessage: new copy of message for Thread[HLRTestServer.2,5,main] DEBUG 2008-07-07 00:45:40,282 [HLRTestServer.2] org.mule.transformer.TransformerTemplate: Applying transformer TransformerTemplate (org.mule.transformer.TransformerTemplate) DEBUG 2008-07-07 00:45:40,282 [HLRTestServer.2] org.mule.transformer.TransformerTemplate: Object before transform: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@3f9261de{id=422f14aa-4bad-11dd-89e9-25c00949723f, payload=org.mule.transport.tcp.TcpMessageReceiver$TcpWorker$1, corre... DEBUG 2008-07-07 00:45:40,282 [HLRTestServer.2] org.mule.transformer.TransformerTemplate: Object after transform: MuleEvent: 422f14ac-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultInboundEndpoint{endpointUri=tcp://localhost:7777, connector=TcpConnector{this=7f371a59, started=true, initial... DEBUG 2008-07-07 00:45:40,282 [HLRTestServer.2] org.mule.transformer.TransformerTemplate: The transformed object is of expected type. Type is: DefaultMuleEventContext INFO 2008-07-07 00:45:40,294 [jdbcConnector.receiver.2] za.co.neotel.provision2.node.hlr.HLRConnectSvc: Receive process with processIdnull INFO 2008-07-07 00:45:40,294 [Thread-3] za.co.neotel.provision2.node.hlr.HuaweiHLRIO: Client@433c8540Server Found ERROR 2008-07-07 00:45:40,305 [Thread-3] za.co.neotel.provision2.node.hlr.HuaweiHLRIO: Client:Client@433c8540 sending login packet. ERROR 2008-07-07 00:46:00,310 [Thread-3] za.co.neotel.provision2.node.hlr.HuaweiHLRIO: Client:Client@433c8540 sending login packet. *** Responsenull Request received at 1215384340294 Response received at 1215384360327 Response received at -20033 ERROR 2008-07-07 00:46:00,327 [jdbcConnector.receiver.2] za.co.neotel.provision2.node.hlr.HuaweiHLRIO: Client:Client@433c8540Polling loop stopping DEBUG 2008-07-07 00:46:00,327 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: CorrelationId is already set to '9830400' , not setting it again DEBUG 2008-07-07 00:46:00,327 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Message being sent to: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@5fcbc39b{id=422b6b24-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=9830400, correlationGroup=-1, correlationSeq=-1, encoding=UTF-8, exceptionPayload=null, properties=Properites{invocation:{}, inbound:{}, outbound:{MULE_BPM_PROCESS_ID=9830400, MULE_CORRELATION_ID=9830400, MULE_ENDPOINT=vm://hlr.requests, MULE_BPM_STARTED=Mon Jul 07 00:45:39 SAST 2008, MULE_ORIGINATING_ENDPOINT=HLRSyncSvc, MULE_BPM_PROCESS_TYPE=getsubinfo, MULE_BPM_ENDPOINT=HLRSyncSvc}, session:{}, }} DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: MuleSession has received synchronous event on endpoint: DefaultOutboundEndpoint{endpointUri=vm://customer.hlrresponse, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderHLRResponse', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Creating event with data: java.lang.String, for endpoint: DefaultOutboundEndpoint{endpointUri=vm://customer.hlrresponse, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderHLRResponse', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event: MuleEvent: 4e2250be-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultOutboundEndpoint{endpointUri=vm://customer.hlrresponse, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderHLRResponse', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding property to session header: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQyMmI2YjI1LTRiYWQtMTFkZC04OWU5LTI1YzAwOTQ5NzIzZg== DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowing a dispatcher for endpoint: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed a dispatcher for endpoint: vm://customer.hlrresponse = VMMessageDispatcher{this=3a97263f, endpoint=vm://customer.hlrresponse, disposed=false} DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed dispatcher: VMMessageDispatcher{this=3a97263f, endpoint=vm://customer.hlrresponse, disposed=false} DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connecting: VMMessageDispatcher{this=3a97263f, endpoint=vm://customer.hlrresponse, disposed=false} INFO 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connected: VMMessageDispatcher{this=3a97263f, endpoint=vm://customer.hlrresponse, disposed=false} DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Looking up vm receiver for address: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Found exact receiver match on endpointUri: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding property to session header: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQyMmI2YjI1LTRiYWQtMTFkZC04OWU5LTI1YzAwOTQ5NzIzZg== DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageReceiver: Message Received from: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Applying transformer Base64Decoder (org.mule.transformer.codec.Base64Decoder) DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object before transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQyMmI2YjI1LTRiYWQtMTFkZC04OWU5LTI1YzAwOTQ5NzIzZg== DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object after transform: {73,68,61,52,50,50,98,54,98,50,53,45,52,98,97,100,45,49,49,100,100,45,56,57,101,57,45,50,53,99,48,48,57,52,57,55,50,51,102,59,73,68,61,52,50,50,98,54,98,50 [..]} DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: The transformed object is of expected type. Type is: byte[] DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Parsing session header: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Added MuleSession variable: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Added MuleSession variable: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Got session with id: 422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event to service: ToBPMS event is: MuleEvent: 4e22ed00-4bad-11dd-89e9-25c00949723f, sync=true, stop processing=false, DefaultInboundEndpoint{endpointUri=vm://customer.hlrresponse, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderHLRResponse', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: Service: ToBPMS has received synchronous event on: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: ToBPMS : got proxy for 4e22ed00-4bad-11dd-89e9-25c00949723f = org.mule.component.DefaultJavaComponent component for: ToBPMS INFO 2008-07-07 00:46:00,333 [jdbcConnector.receiver.2] org.mule.component.simple.LogComponent: ******************************************************************************** * Message received in service: ToBPMS. Content is: 'Error - Request Timed Out' * ******************************************************************************** DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Applying transformer TransformerTemplate (org.mule.transformer.TransformerTemplate) DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object before transform: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@7a5e832b{id=422b6b24-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=9830400, correlationGroup=-1... DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object after transform: Error - Request Timed Out DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: CorrelationId is already set to '9830400' , not setting it again DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Message being sent to: bpm://getsubinfo DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@7a5e832b{id=422b6b24-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=9830400, correlationGroup=-1, correlationSeq=-1, encoding=UTF-8, exceptionPayload=null, properties=Properites{invocation:{}, inbound:{}, outbound:{MULE_BPM_PROCESS_ID=9830400, MULE_CORRELATION_ID=9830400, MULE_ENDPOINT=vm://customer.hlrresponse, MULE_BPM_STARTED=Mon Jul 07 00:45:39 SAST 2008, MULE_ORIGINATING_ENDPOINT=ServiceOrderHLRResponse, MULE_BPM_PROCESS_TYPE=getsubinfo, MULE_BPM_ENDPOINT=HLRSyncSvc}, session:{}, }} DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: MuleSession has received synchronous event on endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Creating event with data: java.lang.String, for endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event: MuleEvent: 4e236231-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding property to session header: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQyMmI2YjI1LTRiYWQtMTFkZC04OWU5LTI1YzAwOTQ5NzIzZg== DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:46:00,336 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowing a dispatcher for endpoint: bpm://getsubinfo DEBUG 2008-07-07 00:46:00,336 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowed a dispatcher for endpoint: bpm://getsubinfo = ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:46:00,336 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowed dispatcher: ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:46:00,336 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Connecting: ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} INFO 2008-07-07 00:46:00,336 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Connected: ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:46:00,337 [jdbcConnector.receiver.2] org.jbpm.configuration.JbpmContextInfo: creating jbpm context with service factories '[tx, message, scheduler, logging, persistence, authentication]' DEBUG 2008-07-07 00:46:00,337 [jdbcConnector.receiver.2] org.jbpm.JbpmContext: creating org.jbpm.JbpmContext@2bbd83d DEBUG 2008-07-07 00:46:00,337 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceServiceFactory: creating persistence service DEBUG 2008-07-07 00:46:00,337 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: creating hibernate session DEBUG 2008-07-07 00:46:00,337 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: beginning hibernate transaction DEBUG 2008-07-07 00:46:00,339 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: begun hibernate transaction org.hibernate.transaction.JDBCTransaction@441944ae DEBUG 2008-07-07 00:46:00,593 [jdbcConnector.receiver.2] org.jbpm.JbpmContext: closing jbpmContext org.jbpm.JbpmContext@2bbd83d DEBUG 2008-07-07 00:46:00,593 [jdbcConnector.receiver.2] org.jbpm.svc.Services: closing service 'persistence': org.jbpm.persistence.db.DbPersistenceService@ea25c1 DEBUG 2008-07-07 00:46:00,593 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: rolling back hibernate transaction DEBUG 2008-07-07 00:46:00,594 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: closing hibernate session WARN 2008-07-07 00:46:00,594 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: SQL Warning: 10000, SQLState: 01J01 WARN 2008-07-07 00:46:00,594 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: Database 'muleEmbeddedDB' not created, connection made to existing database instead. DEBUG 2008-07-07 00:46:00,594 [jdbcConnector.receiver.2] org.jbpm.svc.Services: closing service 'tx': org.jbpm.tx.TxService@c54a25f DEBUG 2008-07-07 00:46:00,594 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Disconnecting: ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} INFO 2008-07-07 00:46:00,595 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Disconnected: ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:46:00,601 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Returning dispatcher for endpoint: bpm://getsubinfo = ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=true} ERROR 2008-07-07 00:46:00,653 [jdbcConnector.receiver.2] org.mule.service.DefaultServiceExceptionStrategy: ******************************************************************************** Message : Failed to route event via endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8}. Message payload is of type: String Type : org.mule.api.transport.DispatchException Code : MULE_ERROR-42999 JavaDoc : http://mule.mulesource.org/docs/apidocs/org/mule/api/transport/DispatchException.html Payload : Error - Request Timed Out ******************************************************************************** Exception stack is: 1. No row with the given identifier exists: [org.jbpm.graph.exe.ProcessInstance#9830400] (org.hibernate.ObjectNotFoundException) org.hibernate.impl.SessionFactoryImpl$1:377 (null) 2. Failed to route event via endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8}. Message payload is of type: String (org.mule.api.transport.DispatchException) org.mule.transport.AbstractMessageDispatcher:188 (http://mule.mulesource.org/docs/apidocs/org/mule/api/transport/DispatchException.html) ******************************************************************************** Root Exception stack trace: org.hibernate.ObjectNotFoundException: No row with the given identifier exists: [org.jbpm.graph.exe.ProcessInstance#9830400] at org.hibernate.impl.SessionFactoryImpl$1.handleEntityNotFound(SessionFactoryImpl.java:377) ======================================================================================= Config: <?xml version="1.0" encoding="UTF-8"?> <!--<process-definition xmlns="urn:jbpm.org:jpdl-3.2" name="getsubinfo">--> <process-definition name="getsubinfo"> <start-state name="recvRequest"> <transition to="receiveRequest"></transition> </start-state> <state name="receiveRequest"> <event type="node-enter"> <action class="org.mule.transport.bpm.jbpm.actions.ValidateMessageSource"> <expectedSource>ServiceOrderRequests</expectedSource> </action> <action class="org.mule.transport.bpm.jbpm.actions.StoreIncomingData"> <variable>recvRequest</variable> </action> <action class="org.mule.transport.bpm.jbpm.actions.Continue"> </action> </event> <transition to="sendMessage"></transition> </state> <state name="sendMessage"> <event type="node-enter"> <action name="sendRequest" class="org.mule.transport.bpm.jbpm.actions.SendMuleEventAndContinue"> <!-- This is the variable we stored in the previous step --> <payloadSource>recvRequest.orderId</payloadSource> <endpoint>HLRSyncSvc</endpoint> </action> </event> <transition to="ReceiveInfo"></transition> </state> <state name="ReceiveInfo"> <event type="node-enter"> <action class="org.mule.providers.bpm.jbpm.actions.StoreIncomingData"> <variable> hlrResponse </variable> <variable> result </variable> <variable> response </variable> </action> </event> <transition to="decision1"></transition> </state> <decision name="decision1" expression="result='Error - Request Timed Out'"> <transition to="end-state1" name="false"></transition> <transition to="end-state2" name="true"></transition> </decision> <end-state name="end-state1"></end-state> <end-state name="end-state2"></end-state> </process-definition> --------------------------------------------------------------------- To unsubscribe from this list, please visit: http://xircles.codehaus.org/manage_email |
|
|
JBPM process not being saved as expectedHi
I'm using Mule 2.0.1, an am picking up the error that my process state isn't being saved into the database. I am using the SendMuleEventAndContinue method and expect the process state to be saved after the event is sent. I've attached a log snippet + my config at the end. Process with ID 9830400 is not being saved, hence the error. Regards Kiren Pillay Tau Space South Africa org.mule.model.resolvers.MethodHeaderPropertyEntryPointResolver: Invoking za.co.neotel.provision2.mule.ServiceOrderStripper.getOrderParameters({class java.lang.String}) ***********<ServiceOrder><OrderId>26</OrderId><Type>NewSubscriber</Type><IMSI>654530200232311</IMSI><CDN>27110200064</CDN><ESN>1r54t8i9</ESN><Status>PE</Status><OrderParameters><General><FirstName>shalendran</FirstName><MiddleName>s</MiddleName><Surname>shalen</Surname><IdNo>5706120109183</IdNo><KI>6551232560230110</KI><PackageName>Neo Connect Unlimited</PackageName><Template></Template></General><Voice><Service name="voice">Neo Connect Unlimited</Service></Voice><Email><Service><EmailAddress>0110200064@...</EmailAddress><Password>12061957</Password><MailPlan>NeomailExpertMail</MailPlan></Service></Email></OrderParameters></ServiceOrder>*************** xpath26 DEBUG 2008-07-07 00:45:39,586 [jdbcConnector.receiver.2] org.mule.model.resolvers.MethodHeaderPropertyEntryPointResolver: Result of call za.co.neotel.provision2.mule.ServiceOrderStripper.getOrderParameters({class java.lang.String}) is not null DEBUG 2008-07-07 00:45:39,588 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Applying transformer TransformerTemplate (org.mule.transformer.TransformerTemplate) DEBUG 2008-07-07 00:45:39,589 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object before transform: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@4da42824{id=41b7c1bd-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=null, correlationGroup=-1, c... DEBUG 2008-07-07 00:45:39,589 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object after transform: za.co.neotel.provision2.messages.ServiceOrder@54477b4e DEBUG 2008-07-07 00:45:39,589 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: The transformed object is of expected type. Type is: ServiceOrder DEBUG 2008-07-07 00:45:39,589 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: No CorrelationId is set on the message, will set a new Id DEBUG 2008-07-07 00:45:39,589 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Extracted correlation Id as: 41b7c1bd-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Setting Correlation info on Outbound router for endpoint: vm://customer.requests Id=41b7c1bd-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Message being sent to: vm://customer.requests DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@4da42824{id=41b7c1bd-4bad-11dd-89e9-25c00949723f, payload=za.co.neotel.provision2.messages.ServiceOrder, correlationId=41b7c1bd-4bad-11dd-89e9-25c00949723f, correlationGroup=-1, correlationSeq=-1, encoding=UTF-8, exceptionPayload=null, properties=Properites{invocation:{}, inbound:{}, outbound:{MULE_ORIGINATING_ENDPOINT=getServiceOrders, MULE_CORRELATION_ID=41b7c1bd-4bad-11dd-89e9-25c00949723f}, session:{}, }} DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: MuleSession has received synchronous event on endpoint: DefaultOutboundEndpoint{endpointUri=vm://customer.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderRequests', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Creating event with data: za.co.neotel.provision2.messages.ServiceOrder, for endpoint: DefaultOutboundEndpoint{endpointUri=vm://customer.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderRequests', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event: MuleEvent: 41c5f290-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultOutboundEndpoint{endpointUri=vm://customer.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderRequests', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,614 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:45:39,614 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,618 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:39,618 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:39,618 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowing a dispatcher for endpoint: vm://customer.requests DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed a dispatcher for endpoint: vm://customer.requests = VMMessageDispatcher{this=71b456f, endpoint=vm://customer.requests, disposed=false} DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed dispatcher: VMMessageDispatcher{this=71b456f, endpoint=vm://customer.requests, disposed=false} DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connecting: VMMessageDispatcher{this=71b456f, endpoint=vm://customer.requests, disposed=false} INFO 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connected: VMMessageDispatcher{this=71b456f, endpoint=vm://customer.requests, disposed=false} DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Looking up vm receiver for address: vm://customer.requests DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Found exact receiver match on endpointUri: vm://customer.requests DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageReceiver: Message Received from: vm://customer.requests DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Applying transformer Base64Decoder (org.mule.transformer.codec.Base64Decoder) DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object before transform: SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object after transform: {73,68,61,52,49,98,56,56,53,48,101,45,52,98,97,100,45,49,49,100,100,45,56,57,101,57,45,50,53,99,48,48,57,52,57,55,50,51,102} DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: The transformed object is of expected type. Type is: byte[] DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Parsing session header: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Added MuleSession variable: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Got session with id: 41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event to service: ToBPMS event is: MuleEvent: 41cb70d2-4bad-11dd-89e9-25c00949723f, sync=true, stop processing=false, DefaultInboundEndpoint{endpointUri=vm://customer.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderRequests', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: Service: ToBPMS has rec*eived synchronous event on: vm://customer.requests DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: ToBPMS : got proxy for 41cb70d2-4bad-11dd-89e9-25c00949723f = org.mule.component.DefaultJavaComponent component for: ToBPMS DEBUG 2008-07-07 00:45:39,627 [jdbcConnector.receiver.2] org.mule.registry.TransientRegistry: lookupObjects: type=interface org.mule.api.transformer.Transformer scope=2 DEBUG 2008-07-07 00:45:39,627 [jdbcConnector.receiver.2] org.mule.config.spring.SpringRegistry: lookupObjects: type=interface org.mule.api.transformer.Transformer scope=2 DEBUG 2008-07-07 00:45:39,635 [jdbcConnector.receiver.2] org.mule.transformer.simple.ObjectToString: java.lang.Object has been added as source type for this transformer, there will be no source type checking performed DEBUG 2008-07-07 00:45:39,637 [jdbcConnector.receiver.2] org.mule.transformer.simple.ObjectToString: Applying transformer ObjectToString (org.mule.transformer.simple.ObjectToString) DEBUG 2008-07-07 00:45:39,637 [jdbcConnector.receiver.2] org.mule.transformer.simple.ObjectToString: Object before transform: za.co.neotel.provision2.messages.ServiceOrder@54477b4e DEBUG 2008-07-07 00:45:39,637 [jdbcConnector.receiver.2] org.mule.transformer.simple.ObjectToString: Object after transform: za.co.neotel.provision2.messages.ServiceOrder@54477b4e DEBUG 2008-07-07 00:45:39,637 [jdbcConnector.receiver.2] org.mule.transformer.simple.ObjectToString: The transformed object is of expected type. Type is: String INFO 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.component.simple.LogComponent: ******************************************************************************** * Message received in service: ToBPMS. Content is: * * 'za.co.neotel.provision2.messages.ServiceOrder@54477b4e' * ******************************************************************************** DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Applying transformer TransformerTemplate (org.mule.transformer.TransformerTemplate) DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object before transform: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@15136019{id=41b7c1bd-4bad-11dd-89e9-25c00949723f, payload=za.co.neotel.provision2.messages.ServiceOrder, correlationId... DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object after transform: za.co.neotel.provision2.messages.ServiceOrder@54477b4e DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: The transformed object is of expected type. Type is: ServiceOrder DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: CorrelationId is already set to '41b7c1bd-4bad-11dd-89e9-25c00949723f' , not setting it again DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Message being sent to: bpm://getsubinfo DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@15136019{id=41b7c1bd-4bad-11dd-89e9-25c00949723f, payload=za.co.neotel.provision2.messages.ServiceOrder, correlationId=41b7c1bd-4bad-11dd-89e9-25c00949723f, correlationGroup=-1, correlationSeq=-1, encoding=UTF-8, exceptionPayload=null, properties=Properites{invocation:{}, inbound:{}, outbound:{MULE_ORIGINATING_ENDPOINT=ServiceOrderRequests, MULE_CORRELATION_ID=41b7c1bd-4bad-11dd-89e9-25c00949723f, MULE_ENDPOINT=vm://customer.requests}, session:{}, }} DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: MuleSession has received synchronous event on endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Creating event with data: za.co.neotel.provision2.messages.ServiceOrder, for endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event: MuleEvent: 41cd4593-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding property to session header: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=41b8850e-4bad-11dd-89e9-25c00949723f;ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=41b8850e-4bad-11dd-89e9-25c00949723f;ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQxYjg4NTBlLTRiYWQtMTFkZC04OWU5LTI1YzAwOTQ5NzIzZg== DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowing a dispatcher for endpoint: bpm://getsubinfo DEBUG 2008-07-07 00:45:39,640 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowed a dispatcher for endpoint: bpm://getsubinfo = ProcessMessageDispatcher{this=22aed3a5, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:45:39,640 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowed dispatcher: ProcessMessageDispatcher{this=22aed3a5, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:45:39,640 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Connecting: ProcessMessageDispatcher{this=22aed3a5, endpoint=bpm://getsubinfo, disposed=false} INFO 2008-07-07 00:45:39,641 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Connected: ProcessMessageDispatcher{this=22aed3a5, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:45:39,680 [jdbcConnector.receiver.2] org.jbpm.configuration.JbpmContextInfo: creating jbpm context with service factories '[tx, message, scheduler, logging, persistence, authentication]' DEBUG 2008-07-07 00:45:39,681 [jdbcConnector.receiver.2] org.jbpm.JbpmContext: creating org.jbpm.JbpmContext@782bbb7b DEBUG 2008-07-07 00:45:39,681 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceServiceFactory: creating persistence service DEBUG 2008-07-07 00:45:39,681 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: creating hibernate session DEBUG 2008-07-07 00:45:39,681 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: beginning hibernate transaction DEBUG 2008-07-07 00:45:39,683 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: begun hibernate transaction org.hibernate.transaction.JDBCTransaction@17cb0a16 WARN 2008-07-07 00:45:39,691 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: SQL Warning: 10000, SQLState: 01J01 WARN 2008-07-07 00:45:39,691 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: Database 'muleEmbeddedDB' not created, connection made to existing database instead. WARN 2008-07-07 00:45:39,693 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: SQL Warning: 10000, SQLState: 01J01 WARN 2008-07-07 00:45:39,693 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: Database 'muleEmbeddedDB' not created, connection made to existing database instead. DEBUG 2008-07-07 00:45:39,706 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'process-start' on 'ProcessDefinition(getsubinfo)' for 'Token(/)' DEBUG 2008-07-07 00:45:39,739 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'incomingSource' in 'TokenVariableMap7ea06d25' with value 'ServiceOrderRequests' DEBUG 2008-07-07 00:45:39,749 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'D', 'org.jbpm.context.exe.converter.DoubleToStringConverter' DEBUG 2008-07-07 00:45:39,749 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'C', 'org.jbpm.context.exe.converter.CharacterToStringConverter' DEBUG 2008-07-07 00:45:39,750 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'B', 'org.jbpm.context.exe.converter.BooleanToStringConverter' DEBUG 2008-07-07 00:45:39,754 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'Y', 'org.jbpm.context.exe.converter.BytesToByteArrayConverter' DEBUG 2008-07-07 00:45:39,755 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'A', 'org.jbpm.context.exe.converter.DateToLongConverter' DEBUG 2008-07-07 00:45:39,756 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'R', 'org.jbpm.context.exe.converter.SerializableToByteArrayConverter' DEBUG 2008-07-07 00:45:39,757 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'I', 'org.jbpm.context.exe.converter.IntegerToLongConverter' DEBUG 2008-07-07 00:45:39,757 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'H', 'org.jbpm.context.exe.converter.ShortToLongConverter' DEBUG 2008-07-07 00:45:39,758 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'G', 'org.jbpm.context.exe.converter.FloatToDoubleConverter' DEBUG 2008-07-07 00:45:39,759 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'F', 'org.jbpm.context.exe.converter.FloatToStringConverter' DEBUG 2008-07-07 00:45:39,759 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'E', 'org.jbpm.context.exe.converter.ByteToLongConverter' DEBUG 2008-07-07 00:45:39,820 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'MULE_SESSION' in 'TokenVariableMap7ea06d25' with value 'SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQxYjg4NTBlLTRiYWQtMTFkZC04OWU5LTI1YzAwOTQ5NzIzZg==' DEBUG 2008-07-07 00:45:39,820 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'MULE_CORRELATION_ID' in 'TokenVariableMap7ea06d25' with value '41b7c1bd-4bad-11dd-89e9-25c00949723f' DEBUG 2008-07-07 00:45:39,820 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'MULE_ENDPOINT' in 'TokenVariableMap7ea06d25' with value 'bpm://getsubinfo' DEBUG 2008-07-07 00:45:39,820 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'incoming' in 'TokenVariableMap7ea06d25' with value 'za.co.neotel.provision2.messages.ServiceOrder@54477b4e' DEBUG 2008-07-07 00:45:39,840 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'MULE_ORIGINATING_ENDPOINT' in 'TokenVariableMap7ea06d25' with value 'ServiceOrderRequests' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'incomingSource' in 'TokenVariableMap7ea06d25' to value 'ServiceOrderRequests' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'MULE_SESSION' in 'TokenVariableMap7ea06d25' to value 'SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQxYjg4NTBlLTRiYWQtMTFkZC04OWU5LTI1YzAwOTQ5NzIzZg==' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'MULE_CORRELATION_ID' in 'TokenVariableMap7ea06d25' to value '41b7c1bd-4bad-11dd-89e9-25c00949723f' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'MULE_ENDPOINT' in 'TokenVariableMap7ea06d25' to value 'bpm://getsubinfo' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'incoming' in 'TokenVariableMap7ea06d25' to value 'za.co.neotel.provision2.messages.ServiceOrder@54477b4e' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'MULE_ORIGINATING_ENDPOINT' in 'TokenVariableMap7ea06d25' to value 'ServiceOrderRequests' DEBUG 2008-07-07 00:45:39,868 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'before-signal' on 'StartState(recvRequest)' for 'Token(/)' DEBUG 2008-07-07 00:45:39,893 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'node-leave' on 'StartState(recvRequest)' for 'Token(/)' DEBUG 2008-07-07 00:45:39,894 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'transition' on 'Transition(1af2f973)' for 'Token(/)' DEBUG 2008-07-07 00:45:39,964 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'node-enter' on 'State(receiveRequest)' for 'Token(/)' DEBUG 2008-07-07 00:45:40,036 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: executing action 'Action(21533b2c)' DEBUG 2008-07-07 00:45:40,036 [jdbcConnector.receiver.2] org.jbpm.graph.exe.Token: token[9863168] is locked by token[9863168] DEBUG 2008-07-07 00:45:40,074 [jdbcConnector.receiver.2] org.mule.transport.bpm.jbpm.actions.ValidateMessageSource: Executing action org.mule.transport.bpm.jbpm.actions.ValidateMessageSource from state "receiveRequest" DEBUG 2008-07-07 00:45:40,074 [jdbcConnector.receiver.2] org.mule.transport.bpm.jbpm.actions.ValidateMessageSource: Validating message source = ServiceOrderRequests, expected = ServiceOrderRequests DEBUG 2008-07-07 00:45:40,074 [jdbcConnector.receiver.2] org.jbpm.graph.exe.Token: token[9863168] is unlocked by token[9863168] DEBUG 2008-07-07 00:45:40,075 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: executing action 'Action(5f51d6cb)' DEBUG 2008-07-07 00:45:40,075 [jdbcConnector.receiver.2] org.jbpm.graph.exe.Token: token[9863168] is locked by token[9863168] DEBUG 2008-07-07 00:45:40,079 [jdbcConnector.receiver.2] org.mule.transport.bpm.jbpm.actions.StoreIncomingData: Executing action org.mule.transport.bpm.jbpm.actions.StoreIncomingData from state "receiveRequest" DEBUG 2008-07-07 00:45:40,088 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'recvRequest' in 'TokenVariableMap7ea06d25' with value 'za.co.neotel.provision2.messages.ServiceOrder@54477b4e' DEBUG 2008-07-07 00:45:40,088 [jdbcConnector.receiver.2] org.jbpm.graph.exe.Token: token[9863168] is unlocked by token[9863168] DEBUG 2008-07-07 00:45:40,088 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: executing action 'Action(75ecda50)' DEBUG 2008-07-07 00:45:40,088 [jdbcConnector.receiver.2] org.jbpm.graph.exe.Token: token[9863168] is locked by token[9863168] DEBUG 2008-07-07 00:45:40,092 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'node-leave' on 'State(receiveRequest)' for 'Token(/)' DEBUG 2008-07-07 00:45:40,092 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'transition' on 'Transition(18170f98)' for 'Token(/)' DEBUG 2008-07-07 00:45:40,094 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'node-enter' on 'State(sendMessage)' for 'Token(/)' DEBUG 2008-07-07 00:45:40,128 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: executing action 'action[sendRequest]' DEBUG 2008-07-07 00:45:40,133 [jdbcConnector.receiver.2] org.mule.transport.bpm.jbpm.actions.SendMuleEventAndContinue: Executing action org.mule.transport.bpm.jbpm.actions.SendMuleEventAndContinue from state "sendMessage" DEBUG 2008-07-07 00:45:40,254 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageReceiver: Executing process is sending an event (synchronously) to Mule endpoint = HLRSyncSvc DEBUG 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageReceiver: Message Received from: bpm://getsubinfo DEBUG 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: There is no session id on the request using key: ID. Generating new session id: 422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event to service: FromBPMS event is: MuleEvent: 422b6b26-4bad-11dd-89e9-25c00949723f, sync=true, stop processing=false, DefaultInboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='ProcessEngine', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: Service: FromBPMS has received synchronous event on: bpm://getsubinfo DEBUG 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: FromBPMS : got proxy for 422b6b26-4bad-11dd-89e9-25c00949723f = org.mule.component.DefaultJavaComponent component for: FromBPMS INFO 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.component.simple.LogComponent: ******************************************************************************** * Message received in service: FromBPMS. Content is: '26' * ******************************************************************************** DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Applying transformer TransformerTemplate (org.mule.transformer.TransformerTemplate) DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object before transform: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@6bfe3238{id=422b6b24-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=9830400, correlationGroup=-1... DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object after transform: 26 DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: CorrelationId is already set to '9830400' , not setting it again DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Message being sent to: vm://hlr.requests DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@6bfe3238{id=422b6b24-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=9830400, correlationGroup=-1, correlationSeq=-1, encoding=UTF-8, exceptionPayload=null, properties=Properites{invocation:{}, inbound:{}, outbound:{MULE_BPM_STARTED=Mon Jul 07 00:45:39 SAST 2008, MULE_ORIGINATING_ENDPOINT=ProcessEngine, MULE_BPM_PROCESS_TYPE=getsubinfo, MULE_BPM_PROCESS_ID=9830400, MULE_CORRELATION_ID=9830400, MULE_BPM_ENDPOINT=HLRSyncSvc}, session:{}, }} DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: MuleSession has received synchronous event on endpoint: DefaultOutboundEndpoint{endpointUri=vm://hlr.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='HLRSyncSvc', properties={method=getInfo}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Creating event with data: java.lang.String, for endpoint: DefaultOutboundEndpoint{endpointUri=vm://hlr.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='HLRSyncSvc', properties={method=getInfo}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event: MuleEvent: 422b9237-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultOutboundEndpoint{endpointUri=vm://hlr.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='HLRSyncSvc', properties={method=getInfo}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowing a dispatcher for endpoint: vm://hlr.requests DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed a dispatcher for endpoint: vm://hlr.requests = VMMessageDispatcher{this=7d9331eb, endpoint=vm://hlr.requests, disposed=false} DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed dispatcher: VMMessageDispatcher{this=7d9331eb, endpoint=vm://hlr.requests, disposed=false} DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connecting: VMMessageDispatcher{this=7d9331eb, endpoint=vm://hlr.requests, disposed=false} INFO 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connected: VMMessageDispatcher{this=7d9331eb, endpoint=vm://hlr.requests, disposed=false} DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Looking up vm receiver for address: vm://hlr.requests DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Found exact receiver match on endpointUri: vm://hlr.requests DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageReceiver: Message Received from: vm://hlr.requests DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Applying transformer Base64Decoder (org.mule.transformer.codec.Base64Decoder) DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object before transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object after transform: {73,68,61,52,50,50,98,54,98,50,53,45,52,98,97,100,45,49,49,100,100,45,56,57,101,57,45,50,53,99,48,48,57,52,57,55,50,51,102} DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: The transformed object is of expected type. Type is: byte[] DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Parsing session header: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Added MuleSession variable: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Got session with id: 422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event to service: HLRService event is: MuleEvent: 422be059-4bad-11dd-89e9-25c00949723f, sync=true, stop processing=false, DefaultInboundEndpoint{endpointUri=vm://hlr.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='HLRSyncSvc', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: Service: HLRService has received synchronous event on: vm://hlr.requests DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: HLRService : got proxy for 422be059-4bad-11dd-89e9-25c00949723f = org.mule.component.DefaultJavaComponent component for: HLRService INFO 2008-07-07 00:45:40,261 [jdbcConnector.receiver.2] za.co.neotel.provision2.common.Settings: Initialising settings DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.transport.tcp.TcpMessageReceiver: Message Received from: tcp://localhost:7777 DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.DefaultMuleSession: There is no session id on the request using key: ID. Generating new session id: 422f14ab-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.DefaultMuleSession: dispatching event to service: HLRTestServer, event is: MuleEvent: 422f14ac-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultInboundEndpoint{endpointUri=tcp://localhost:7777, connector=TcpConnector{this=7f371a59, started=true, initialised=true, name='TCPConn', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[tcp], serviceOverrides=null}, transformer=[], name='SocketToHLR', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.model.seda.SedaService: Service: HLRTestServer has received asynchronous event on: tcp://localhost:7777 DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.model.seda.SedaService: Service: HLRTestServer has received asynchronous event on: tcp://localhost:7777 DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.registry.TransientRegistry: lookupObject: key=_muleQueueManager scope=2 DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.registry.TransientRegistry: Failed to find object in Registry ID: org.mule.Registry.Transient DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.config.spring.SpringRegistry: lookupObject: key=_muleQueueManager scope=2 DEBUG 2008-07-07 00:45:40,280 [TCPConn.receiver.2] org.mule.model.seda.SedaService: Service HLRTestServer putting event on queue HLRTestServer: MuleEvent: 422f14ac-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultInboundEndpoint{endpointUri=tcp://localhost:7777, connector=TcpConnector{this=7f371a59, started=true, initialised=true, name='TCPConn', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[tcp], serviceOverrides=null}, transformer=[], name='SocketToHLR', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,280 [HLRTestServer.1] org.mule.model.seda.SedaService: Service: HLRTestServer dequeued event on: tcp://localhost:7777 DEBUG 2008-07-07 00:45:40,281 [HLRTestServer.2] org.mule.DefaultMuleMessage: new copy of message for Thread[HLRTestServer.2,5,main] DEBUG 2008-07-07 00:45:40,282 [HLRTestServer.2] org.mule.transformer.TransformerTemplate: Applying transformer TransformerTemplate (org.mule.transformer.TransformerTemplate) DEBUG 2008-07-07 00:45:40,282 [HLRTestServer.2] org.mule.transformer.TransformerTemplate: Object before transform: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@3f9261de{id=422f14aa-4bad-11dd-89e9-25c00949723f, payload=org.mule.transport.tcp.TcpMessageReceiver$TcpWorker$1, corre... DEBUG 2008-07-07 00:45:40,282 [HLRTestServer.2] org.mule.transformer.TransformerTemplate: Object after transform: MuleEvent: 422f14ac-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultInboundEndpoint{endpointUri=tcp://localhost:7777, connector=TcpConnector{this=7f371a59, started=true, initial... DEBUG 2008-07-07 00:45:40,282 [HLRTestServer.2] org.mule.transformer.TransformerTemplate: The transformed object is of expected type. Type is: DefaultMuleEventContext INFO 2008-07-07 00:45:40,294 [jdbcConnector.receiver.2] za.co.neotel.provision2.node.hlr.HLRConnectSvc: Receive process with processIdnull INFO 2008-07-07 00:45:40,294 [Thread-3] za.co.neotel.provision2.node.hlr.HuaweiHLRIO: Client@433c8540Server Found ERROR 2008-07-07 00:45:40,305 [Thread-3] za.co.neotel.provision2.node.hlr.HuaweiHLRIO: Client:Client@433c8540 sending login packet. ERROR 2008-07-07 00:46:00,310 [Thread-3] za.co.neotel.provision2.node.hlr.HuaweiHLRIO: Client:Client@433c8540 sending login packet. *** Responsenull Request received at 1215384340294 Response received at 1215384360327 Response received at -20033 ERROR 2008-07-07 00:46:00,327 [jdbcConnector.receiver.2] za.co.neotel.provision2.node.hlr.HuaweiHLRIO: Client:Client@433c8540Polling loop stopping DEBUG 2008-07-07 00:46:00,327 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: CorrelationId is already set to '9830400' , not setting it again DEBUG 2008-07-07 00:46:00,327 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Message being sent to: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@5fcbc39b{id=422b6b24-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=9830400, correlationGroup=-1, correlationSeq=-1, encoding=UTF-8, exceptionPayload=null, properties=Properites{invocation:{}, inbound:{}, outbound:{MULE_BPM_PROCESS_ID=9830400, MULE_CORRELATION_ID=9830400, MULE_ENDPOINT=vm://hlr.requests, MULE_BPM_STARTED=Mon Jul 07 00:45:39 SAST 2008, MULE_ORIGINATING_ENDPOINT=HLRSyncSvc, MULE_BPM_PROCESS_TYPE=getsubinfo, MULE_BPM_ENDPOINT=HLRSyncSvc}, session:{}, }} DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: MuleSession has received synchronous event on endpoint: DefaultOutboundEndpoint{endpointUri=vm://customer.hlrresponse, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderHLRResponse', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Creating event with data: java.lang.String, for endpoint: DefaultOutboundEndpoint{endpointUri=vm://customer.hlrresponse, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderHLRResponse', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event: MuleEvent: 4e2250be-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultOutboundEndpoint{endpointUri=vm://customer.hlrresponse, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderHLRResponse', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding property to session header: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQyMmI2YjI1LTRiYWQtMTFkZC04OWU5LTI1YzAwOTQ5NzIzZg== DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowing a dispatcher for endpoint: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed a dispatcher for endpoint: vm://customer.hlrresponse = VMMessageDispatcher{this=3a97263f, endpoint=vm://customer.hlrresponse, disposed=false} DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed dispatcher: VMMessageDispatcher{this=3a97263f, endpoint=vm://customer.hlrresponse, disposed=false} DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connecting: VMMessageDispatcher{this=3a97263f, endpoint=vm://customer.hlrresponse, disposed=false} INFO 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connected: VMMessageDispatcher{this=3a97263f, endpoint=vm://customer.hlrresponse, disposed=false} DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Looking up vm receiver for address: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Found exact receiver match on endpointUri: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding property to session header: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQyMmI2YjI1LTRiYWQtMTFkZC04OWU5LTI1YzAwOTQ5NzIzZg== DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageReceiver: Message Received from: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Applying transformer Base64Decoder (org.mule.transformer.codec.Base64Decoder) DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object before transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQyMmI2YjI1LTRiYWQtMTFkZC04OWU5LTI1YzAwOTQ5NzIzZg== DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object after transform: {73,68,61,52,50,50,98,54,98,50,53,45,52,98,97,100,45,49,49,100,100,45,56,57,101,57,45,50,53,99,48,48,57,52,57,55,50,51,102,59,73,68,61,52,50,50,98,54,98,50 [..]} DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: The transformed object is of expected type. Type is: byte[] DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Parsing session header: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Added MuleSession variable: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Added MuleSession variable: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Got session with id: 422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event to service: ToBPMS event is: MuleEvent: 4e22ed00-4bad-11dd-89e9-25c00949723f, sync=true, stop processing=false, DefaultInboundEndpoint{endpointUri=vm://customer.hlrresponse, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderHLRResponse', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: Service: ToBPMS has received synchronous event on: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: ToBPMS : got proxy for 4e22ed00-4bad-11dd-89e9-25c00949723f = org.mule.component.DefaultJavaComponent component for: ToBPMS INFO 2008-07-07 00:46:00,333 [jdbcConnector.receiver.2] org.mule.component.simple.LogComponent: ******************************************************************************** * Message received in service: ToBPMS. Content is: 'Error - Request Timed Out' * ******************************************************************************** DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Applying transformer TransformerTemplate (org.mule.transformer.TransformerTemplate) DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object before transform: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@7a5e832b{id=422b6b24-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=9830400, correlationGroup=-1... DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object after transform: Error - Request Timed Out DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: CorrelationId is already set to '9830400' , not setting it again DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Message being sent to: bpm://getsubinfo DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAdapter@7a5e832b{id=422b6b24-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=9830400, correlationGroup=-1, correlationSeq=-1, encoding=UTF-8, exceptionPayload=null, properties=Properites{invocation:{}, inbound:{}, outbound:{MULE_BPM_PROCESS_ID=9830400, MULE_CORRELATION_ID=9830400, MULE_ENDPOINT=vm://customer.hlrresponse, MULE_BPM_STARTED=Mon Jul 07 00:45:39 SAST 2008, MULE_ORIGINATING_ENDPOINT=ServiceOrderHLRResponse, MULE_BPM_PROCESS_TYPE=getsubinfo, MULE_BPM_ENDPOINT=HLRSyncSvc}, session:{}, }} DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: MuleSession has received synchronous event on endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Creating event with data: java.lang.String, for endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event: MuleEvent: 4e236231-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding property to session header: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQyMmI2YjI1LTRiYWQtMTFkZC04OWU5LTI1YzAwOTQ5NzIzZg== DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:46:00,336 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowing a dispatcher for endpoint: bpm://getsubinfo DEBUG 2008-07-07 00:46:00,336 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowed a dispatcher for endpoint: bpm://getsubinfo = ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:46:00,336 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowed dispatcher: ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:46:00,336 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Connecting: ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} INFO 2008-07-07 00:46:00,336 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Connected: ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:46:00,337 [jdbcConnector.receiver.2] org.jbpm.configuration.JbpmContextInfo: creating jbpm context with service factories '[tx, message, scheduler, logging, persistence, authentication]' DEBUG 2008-07-07 00:46:00,337 [jdbcConnector.receiver.2] org.jbpm.JbpmContext: creating org.jbpm.JbpmContext@2bbd83d DEBUG 2008-07-07 00:46:00,337 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceServiceFactory: creating persistence service DEBUG 2008-07-07 00:46:00,337 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: creating hibernate session DEBUG 2008-07-07 00:46:00,337 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: beginning hibernate transaction DEBUG 2008-07-07 00:46:00,339 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: begun hibernate transaction org.hibernate.transaction.JDBCTransaction@441944ae DEBUG 2008-07-07 00:46:00,593 [jdbcConnector.receiver.2] org.jbpm.JbpmContext: closing jbpmContext org.jbpm.JbpmContext@2bbd83d DEBUG 2008-07-07 00:46:00,593 [jdbcConnector.receiver.2] org.jbpm.svc.Services: closing service 'persistence': org.jbpm.persistence.db.DbPersistenceService@ea25c1 DEBUG 2008-07-07 00:46:00,593 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: rolling back hibernate transaction DEBUG 2008-07-07 00:46:00,594 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: closing hibernate session WARN 2008-07-07 00:46:00,594 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: SQL Warning: 10000, SQLState: 01J01 WARN 2008-07-07 00:46:00,594 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: Database 'muleEmbeddedDB' not created, connection made to existing database instead. DEBUG 2008-07-07 00:46:00,594 [jdbcConnector.receiver.2] org.jbpm.svc.Services: closing service 'tx': org.jbpm.tx.TxService@c54a25f DEBUG 2008-07-07 00:46:00,594 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Disconnecting: ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} INFO 2008-07-07 00:46:00,595 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Disconnected: ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:46:00,601 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Returning dispatcher for endpoint: bpm://getsubinfo = ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=true} ERROR 2008-07-07 00:46:00,653 [jdbcConnector.receiver.2] org.mule.service.DefaultServiceExceptionStrategy: ******************************************************************************** Message : Failed to route event via endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8}. Message payload is of type: String Type : org.mule.api.transport.DispatchException Code : MULE_ERROR-42999 JavaDoc : http://mule.mulesource.org/docs/apidocs/org/mule/api/transport/DispatchException.html Payload : Error - Request Timed Out ******************************************************************************** Exception stack is: 1. No row with the given identifier exists: [org.jbpm.graph.exe.ProcessInstance#9830400] (org.hibernate.ObjectNotFoundException) org.hibernate.impl.SessionFactoryImpl$1:377 (null) 2. Failed to route event via endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8}. Message payload is of type: String (org.mule.api.transport.DispatchException) org.mule.transport.AbstractMessageDispatcher:188 (http://mule.mulesource.org/docs/apidocs/org/mule/api/transport/DispatchException.html) ******************************************************************************** Root Exception stack trace: org.hibernate.ObjectNotFoundException: No row with the given identifier exists: [org.jbpm.graph.exe.ProcessInstance#9830400] at org.hibernate.impl.SessionFactoryImpl$1.handleEntityNotFound(SessionFactoryImpl.java:377) ======================================================================================= Config: <?xml version="1.0" encoding="UTF-8"?> <!--<process-definition xmlns="urn:jbpm.org:jpdl-3.2" name="getsubinfo">--> <process-definition name="getsubinfo"> <start-state name="recvRequest"> <transition to="receiveRequest"></transition> </start-state> <state name="receiveRequest"> <event type="node-enter"> <action class="org.mule.transport.bpm.jbpm.actions.ValidateMessageSource"> <expectedSource>ServiceOrderRequests</expectedSource> </action> <action class="org.mule.transport.bpm.jbpm.actions.StoreIncomingData"> <variable>recvRequest</variable> </action> <action class="org.mule.transport.bpm.jbpm.actions.Continue"> </action> </event> <transition to="sendMessage"></transition> </state> <state name="sendMessage"> <event type="node-enter"> <action name="sendRequest" class="org.mule.transport.bpm.jbpm.actions.SendMuleEventAndContinue"> <!-- This is the variable we stored in the previous step --> <payloadSource>recvRequest.orderId</payloadSource> <endpoint>HLRSyncSvc</endpoint> </action> </event> <transition to="ReceiveInfo"></transition> </state> <state name="ReceiveInfo"> <event type="node-enter"> <action class="org.mule.providers.bpm.jbpm.actions.StoreIncomingData"> <variable> hlrResponse </variable> <variable> result </variable> <variable> response </variable> </action> </event> <transition to="decision1"></transition> </state> <decision name="decision1" expression="result='Error - Request Timed Out'"> <transition to="end-state1" name="false"></transition> <transition to="end-state2" name="true"></transition> </decision> <end-state name="end-state1"></end-state> <end-state name="end-state2"></end-state> </process-definition> --------- Process definition file: <?xml version="1.0" encoding="UTF-8"?> <mule xmlns="http://www.mulesource.org/schema/mule/core/2.0" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:spring="http://www.springframework.org/schema/beans" xmlns:context="http://www.springframework.org/schema/context" xmlns:bpm="http://www.mulesource.org/schema/mule/bpm/2.0" xmlns:vm="http://www.mulesource.org/schema/mule/vm/2.0" xmlns:jms="http://www.mulesource.org/schema/mule/jms/2.0" xmlns:jdbc="http://www.mulesource.org/schema/mule/jdbc/2.0" xmlns:tcp="http://www.mulesource.org/schema/mule/tcp/2.0" xsi:schemaLocation=" http://www.springframework.org/schema/beans http://www.springframework.org/schema/beans/spring-beans-2.0.xsd http://www.springframework.org/schema/context http://www.springframework.org/schema/context/spring-context-2.5.xsd http://www.mulesource.org/schema/mule/core/2.0 http://www.mulesource.org/schema/mule/core/2.0/mule.xsd http://www.mulesource.org/schema/mule/bpm/2.0 http://www.mulesource.org/schema/mule/bpm/2.0/mule-bpm.xsd http://www.mulesource.org/schema/mule/vm/2.0 http://www.mulesource.org/schema/mule/vm/2.0/mule-vm.xsd http://www.mulesource.org/schema/mule/jms/2.0 http://www.mulesource.org/schema/mule/jms/2.0/mule-jms.xsd http://www.mulesource.org/schema/mule/jdbc/2.0 http://www.mulesource.org/schema/mule/jdbc/2.0/mule-jdbc.xsd http://www.mulesource.org/schema/mule/tcp/2.0 http://www.mulesource.org/schema/mule/tcp/2.0/mule-tcp.xsd"> <bpm:connector name="jBpmConnector" bpms-ref="jbpm" /> <vm:connector name="syncVm" /> <vm:connector name="asyncVm" queueEvents="true" /> <tcp:connector name="TCPConn"><tcp:streaming-protocol/> </tcp:connector> <jdbc:connector name="jdbcConnector" dataSource-ref="serviceorder-datasource" pollingFrequency="120000"> <!-- <jdbc:query key="getServiceOrders" value="SELECT ID, PRODUCT_ID,ORDERID,STATUS,CDN,ESN,IMSI, ORDER_PARAMETERS,TYPE,ERROR_CODE,ERROR_DESCRIPTION FROM SUGAR.SERVICEORDER " /> --> <jdbc:query key="getServiceOrders" value="SELECT ORDER_PARAMETERS FROM SUGAR.SERVICEORDER LIMIT 0,1" /> <!-- <property name="getTest.ack" value="UPDATE IGOR_SO SET STATUS = 999 where ORDERID = ${orderid}"/> --> </jdbc:connector> <!-- We could use JMS instead of VM to make the example more realistic. <jms:activemq-connector name="jmsConnector" /> --> <!-- The Provisioning is driven by a Process Engine --> <endpoint name="ProcessEngine" address="bpm://getsubinfo" /> <!-- Customer loan requests to the Loan Broker --> <vm:endpoint name="ServiceOrderRequests" path="customer.requests" connector-ref="syncVm" /> <vm:endpoint name="HLRSyncSvc" path="hlr.requests" connector-ref="syncVm" /> <vm:endpoint name="ServiceOrderHLRResponse" path="customer.hlrresponse" connector-ref="syncVm" /> <tcp:endpoint name="SocketToHLR" connector-ref="TCPConn" host="localhost" port="7777" /> <!-- agents --> <!-- connectors --> <model name="loan-broker"> <!-- *************************************************** Process Engine *************************************************** --> <service name="ServiceOrderPoller"> <inbound> <jdbc:inbound-endpoint queryKey="getServiceOrders?method=getOrderParameters" synchronous="true"> <object-to-string-transformer /> </jdbc:inbound-endpoint> </inbound> <!-- <echo-component /> --> <component class="za.co.neotel.provision2.mule.ServiceOrderStripper"> </component> <outbound> <filtering-router> <outbound-endpoint ref="ServiceOrderRequests" synchronous="true" /> </filtering-router> </outbound> </service> <!-- Messages sent to the process engine --> <service name="ToBPMS"> <inbound> <!-- Incoming message = ServiceOrderRequests --> <inbound-endpoint ref="ServiceOrderRequests" /> <!-- Incoming message = ServiceOrderHLRResponse --> <inbound-endpoint ref="ServiceOrderHLRResponse" /> </inbound> <echo-component /> <outbound> <filtering-router> <outbound-endpoint address="bpm://getsubinfo" /> </filtering-router> </outbound> </service> <!-- Messages generated from the process engine --> <service name="FromBPMS"> <inbound> <inbound-endpoint ref="ProcessEngine" /> </inbound> <echo-component /> <outbound> <filtering-router> <!-- Outgoing message = HLRRequest --> <outbound-endpoint ref="HLRSyncSvc" synchronous="true" > <property key="method" value="getInfo" /> </outbound-endpoint> </filtering-router> </outbound> </service> <!-- *************************************************** Services *************************************************** --> <!-- The HLR Service receives requests for the HLR and sends them through --> <service name="HLRService"> <inbound> <inbound-endpoint ref="HLRSyncSvc" /> </inbound> <component class="za.co.neotel.provision2.node.hlr.HLRConnectSvc"> </component> <outbound> <filtering-router> <outbound-endpoint ref="ServiceOrderHLRResponse" /> </filtering-router> </outbound> </service> <service name="HLRTestServer" initialState="started"> <inbound> <inbound-endpoint ref="SocketToHLR" /> </inbound> <component > <singleton-object class="za.co.neotel.simulator.TestHLR" /> </component> </service> </model> <!-- *************************************************** BPMS Configuration *************************************************** --> <!-- <context:property-placeholder location="derby.properties" /> --> <spring:bean id="propertyConfigurer" class="org.springframework.beans.factory.config.PropertyPlaceholderConfigurer"> <spring:property name="locations"> <spring:list> <spring:value>derby.properties</spring:value> </spring:list> </spring:property> <spring:property name="placeholderPrefix" value="$[" /> <spring:property name="placeholderSuffix" value="]" /> </spring:bean> <!-- BPMS object for Mule's BPM Connector --> <spring:bean id="jbpm" class="org.mule.transport.bpm.jbpm.Jbpm" destroy-method="destroy"> <spring:property name="jbpmConfiguration"> <spring:ref local="jbpmConfig" /> </spring:property> </spring:bean> <!-- jBPM Configuration --> <spring:bean id="jbpmConfig" class="org.springmodules.workflow.jbpm31.LocalJbpmConfigurationFactoryBean"> <spring:property name="sessionFactory"> <spring:ref local="jbpmSessionFactory" /> </spring:property> <spring:property name="configuration"> <spring:value>jbpm.cfg.xml</spring:value> </spring:property> <spring:property name="processDefinitions"> <spring:list> <spring:bean id="provisioningBPM" class="org.springmodules.workflow.jbpm31.definition.ProcessDefinitionFactoryBean"> <spring:property name="definitionLocation"> <spring:value> /getsubinfo/processdefinition.xml </spring:value> </spring:property> </spring:bean> </spring:list> </spring:property> <spring:property name="createSchema"> <spring:value>false</spring:value> </spring:property> </spring:bean> <!-- JBPM Hibernate SessionFactory --> <spring:bean id="jbpmSessionFactory" class="org.springframework.orm.hibernate3.LocalSessionFactoryBean"> <spring:property name="dataSource"> <spring:ref local="jbpmDataSource" /> </spring:property> <spring:property name="mappingLocations"> <spring:value> classpath*:/org/jbpm/**/*.hbm.xml </spring:value> </spring:property> <spring:property name="typeDefinitions"> <spring:ref local="jbpmTypes" /> </spring:property> <spring:property name="hibernateProperties"> <spring:props> <spring:prop key="hibernate.dialect"> org.hibernate.dialect.DerbyDialect </spring:prop> <spring:prop key="hibernate.cache.provider_class"> org.hibernate.cache.HashtableCacheProvider </spring:prop> <!-- Create/update the database tables automatically when the JVM starts up --> <spring:prop key="hibernate.hbm2ddl.auto"> update </spring:prop> </spring:props> </spring:property> </spring:bean> <!-- JBPM Datasource --> <spring:bean id="jbpmDataSource" class="org.springframework.jdbc.datasource.DriverManagerDataSource"> <spring:property name="driverClassName"> <spring:value> org.apache.derby.jdbc.EmbeddedDriver </spring:value> </spring:property> <spring:property name="url"> <spring:value>$[database.connection]</spring:value> </spring:property> </spring:bean> <spring:bean name="serviceorder-datasource" class="org.springframework.jdbc.datasource.DriverManagerDataSource"> <spring:property name="driverClassName"> <spring:value>com.mysql.jdbc.Driver</spring:value> </spring:property> <spring:property name="url" value="jdbc:-----------------" /> <spring:property name="username"> <spring:value>$[jdbc.mysql.user]</spring:value> </spring:property> <spring:property name="password"> <spring:value>$[jdbc.mysql.pwd]</spring:value> </spring:property> </spring:bean> <!-- JBPM data types --> <spring:bean id="jbpmTypes" class="org.springframework.orm.hibernate3.TypeDefinitionBean"> <spring:property name="typeName"> <spring:value>string_max</spring:value> </spring:property> <spring:property name="typeClass"> <spring:value>org.jbpm.db.hibernate.StringMax</spring:value> </spring:property> </spring:bean> </mule> --------------------------------------------------------------------- To unsubscribe from this list, please visit: http://xircles.codehaus.org/manage_email |
|
|
RE: JBPM process not being saved as expectedHi,
Where do you use the SendMuleEventAndContinue() method? The error Exception stack is: 1. No row with the given identifier exists: [org.jbpm.graph.exe.ProcessInstance#9830400] (org.hibernate.ObjectNotFoundException) Implies that this process ID cannot be found and I'm not sure if this is in the database or the BPM. A Antoine Borg, Senior Consultant | Tel: +356 21334457 | Fax: +356 21 334156 ricston Ltd., Lincoln, 7 Ferdinand Grech Street, Lija LJA1142, MALTA email: antoine.borg@... | blog: blog.ricston.com | web: ricston.com -----Original Message----- From: Kiren Pillay [mailto:kirenpillay1@...] Sent: Monday, July 07, 2008 10:41 AM To: user@... Subject: [mule-user] JBPM process not being saved as expected Hi I'm using Mule 2.0.1, an am picking up the error that my process state isn't being saved into the database. I am using the SendMuleEventAndContinue method and expect the process state to be saved after the event is sent. I've attached a log snippet + my config at the end. Process with ID 9830400 is not being saved, hence the error. Regards Kiren Pillay Tau Space South Africa org.mule.model.resolvers.MethodHeaderPropertyEntryPointResolver: Invoking za.co.neotel.provision2.mule.ServiceOrderStripper.getOrderParameters({class java.lang.String}) ***********<ServiceOrder><OrderId>26</OrderId><Type>NewSubscriber</Type><IMS I>654530200232311</IMSI><CDN>27110200064</CDN><ESN>1r54t8i9</ESN><Status>PE< /Status><OrderParameters><General><FirstName>shalendran</FirstName><MiddleNa me>s</MiddleName><Surname>shalen</Surname><IdNo>5706120109183</IdNo><KI>6551 232560230110</KI><PackageName>Neo Connect Unlimited</PackageName><Template></Template></General><Voice><Service name="voice">Neo Connect Unlimited</Service></Voice><Email><Service><EmailAddress>0110200064@neomail. co.za</EmailAddress><Password>12061957</Password><MailPlan>NeomailExpertMail </MailPlan></Service></Email></OrderParameters></ServiceOrder>************** * xpath26 DEBUG 2008-07-07 00:45:39,586 [jdbcConnector.receiver.2] org.mule.model.resolvers.MethodHeaderPropertyEntryPointResolver: Result of call za.co.neotel.provision2.mule.ServiceOrderStripper.getOrderParameters({class java.lang.String}) is not null DEBUG 2008-07-07 00:45:39,588 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Applying transformer TransformerTemplate (org.mule.transformer.TransformerTemplate) DEBUG 2008-07-07 00:45:39,589 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object before transform: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAd apter@4da42824{id=41b7c1bd-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=null, correlationGroup=-1, c... DEBUG 2008-07-07 00:45:39,589 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object after transform: za.co.neotel.provision2.messages.ServiceOrder@54477b4e DEBUG 2008-07-07 00:45:39,589 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: The transformed object is of expected type. Type is: ServiceOrder DEBUG 2008-07-07 00:45:39,589 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: No CorrelationId is set on the message, will set a new Id DEBUG 2008-07-07 00:45:39,589 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Extracted correlation Id as: 41b7c1bd-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Setting Correlation info on Outbound router for endpoint: vm://customer.requests Id=41b7c1bd-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Message being sent to: vm://customer.requests DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAd apter@4da42824{id=41b7c1bd-4bad-11dd-89e9-25c00949723f, payload=za.co.neotel.provision2.messages.ServiceOrder, correlationId=41b7c1bd-4bad-11dd-89e9-25c00949723f, correlationGroup=-1, correlationSeq=-1, encoding=UTF-8, exceptionPayload=null, properties=Properites{invocation:{}, inbound:{}, outbound:{MULE_ORIGINATING_ENDPOINT=getServiceOrders, MULE_CORRELATION_ID=41b7c1bd-4bad-11dd-89e9-25c00949723f}, session:{}, }} DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: MuleSession has received synchronous event on endpoint: DefaultOutboundEndpoint{endpointUri=vm://customer.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderRequests', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Creating event with data: za.co.neotel.provision2.messages.ServiceOrder, for endpoint: DefaultOutboundEndpoint{endpointUri=vm://customer.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderRequests', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event: MuleEvent: 41c5f290-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultOutboundEndpoint{endpointUri=vm://customer.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderRequests', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,590 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,614 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:45:39,614 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,618 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:39,618 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:39,618 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowing a dispatcher for endpoint: vm://customer.requests DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed a dispatcher for endpoint: vm://customer.requests = VMMessageDispatcher{this=71b456f, endpoint=vm://customer.requests, disposed=false} DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed dispatcher: VMMessageDispatcher{this=71b456f, endpoint=vm://customer.requests, disposed=false} DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connecting: VMMessageDispatcher{this=71b456f, endpoint=vm://customer.requests, disposed=false} INFO 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connected: VMMessageDispatcher{this=71b456f, endpoint=vm://customer.requests, disposed=false} DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Looking up vm receiver for address: vm://customer.requests DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Found exact receiver match on endpointUri: vm://customer.requests DEBUG 2008-07-07 00:45:39,624 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageReceiver: Message Received from: vm://customer.requests DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Applying transformer Base64Decoder (org.mule.transformer.codec.Base64Decoder) DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object before transform: SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object after transform: {73,68,61,52,49,98,56,56,53,48,101,45,52,98,97,100,45,49,49,100,100,45,56,57 ,101,57,45,50,53,99,48,48,57,52,57,55,50,51,102} DEBUG 2008-07-07 00:45:39,625 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: The transformed object is of expected type. Type is: byte[] DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Parsing session header: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Added MuleSession variable: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Got session with id: 41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event to service: ToBPMS event is: MuleEvent: 41cb70d2-4bad-11dd-89e9-25c00949723f, sync=true, stop processing=false, DefaultInboundEndpoint{endpointUri=vm://customer.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderRequests', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: Service: ToBPMS has rec*eived synchronous event on: vm://customer.requests DEBUG 2008-07-07 00:45:39,626 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: ToBPMS : got proxy for 41cb70d2-4bad-11dd-89e9-25c00949723f = org.mule.component.DefaultJavaComponent component for: ToBPMS DEBUG 2008-07-07 00:45:39,627 [jdbcConnector.receiver.2] org.mule.registry.TransientRegistry: lookupObjects: type=interface org.mule.api.transformer.Transformer scope=2 DEBUG 2008-07-07 00:45:39,627 [jdbcConnector.receiver.2] org.mule.config.spring.SpringRegistry: lookupObjects: type=interface org.mule.api.transformer.Transformer scope=2 DEBUG 2008-07-07 00:45:39,635 [jdbcConnector.receiver.2] org.mule.transformer.simple.ObjectToString: java.lang.Object has been added as source type for this transformer, there will be no source type checking performed DEBUG 2008-07-07 00:45:39,637 [jdbcConnector.receiver.2] org.mule.transformer.simple.ObjectToString: Applying transformer ObjectToString (org.mule.transformer.simple.ObjectToString) DEBUG 2008-07-07 00:45:39,637 [jdbcConnector.receiver.2] org.mule.transformer.simple.ObjectToString: Object before transform: za.co.neotel.provision2.messages.ServiceOrder@54477b4e DEBUG 2008-07-07 00:45:39,637 [jdbcConnector.receiver.2] org.mule.transformer.simple.ObjectToString: Object after transform: za.co.neotel.provision2.messages.ServiceOrder@54477b4e DEBUG 2008-07-07 00:45:39,637 [jdbcConnector.receiver.2] org.mule.transformer.simple.ObjectToString: The transformed object is of expected type. Type is: String INFO 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.component.simple.LogComponent: **************************************************************************** **** * Message received in service: ToBPMS. Content is: * * 'za.co.neotel.provision2.messages.ServiceOrder@54477b4e' * **************************************************************************** **** DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Applying transformer TransformerTemplate (org.mule.transformer.TransformerTemplate) DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object before transform: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAd apter@15136019{id=41b7c1bd-4bad-11dd-89e9-25c00949723f, payload=za.co.neotel.provision2.messages.ServiceOrder, correlationId... DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object after transform: za.co.neotel.provision2.messages.ServiceOrder@54477b4e DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: The transformed object is of expected type. Type is: ServiceOrder DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: CorrelationId is already set to '41b7c1bd-4bad-11dd-89e9-25c00949723f' , not setting it again DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Message being sent to: bpm://getsubinfo DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAd apter@15136019{id=41b7c1bd-4bad-11dd-89e9-25c00949723f, payload=za.co.neotel.provision2.messages.ServiceOrder, correlationId=41b7c1bd-4bad-11dd-89e9-25c00949723f, correlationGroup=-1, correlationSeq=-1, encoding=UTF-8, exceptionPayload=null, properties=Properites{invocation:{}, inbound:{}, outbound:{MULE_ORIGINATING_ENDPOINT=ServiceOrderRequests, MULE_CORRELATION_ID=41b7c1bd-4bad-11dd-89e9-25c00949723f, MULE_ENDPOINT=vm://customer.requests}, session:{}, }} DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: MuleSession has received synchronous event on endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Creating event with data: za.co.neotel.provision2.messages.ServiceOrder, for endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event: MuleEvent: 41cd4593-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:39,638 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding property to session header: ID=41b8850e-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=41b8850e-4bad-11dd-89e9-25c00949723f;ID=41b8850e-4bad-11dd-89e9-25c009497 23f DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=41b8850e-4bad-11dd-89e9-25c00949723f;ID=41b8850e-4bad-11dd-89e9-25c009497 23f DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQxYjg4NTBlLTRiYWQt MTFkZC04OWU5LTI1YzAwOTQ5NzIzZg== DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:39,639 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowing a dispatcher for endpoint: bpm://getsubinfo DEBUG 2008-07-07 00:45:39,640 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowed a dispatcher for endpoint: bpm://getsubinfo = ProcessMessageDispatcher{this=22aed3a5, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:45:39,640 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowed dispatcher: ProcessMessageDispatcher{this=22aed3a5, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:45:39,640 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Connecting: ProcessMessageDispatcher{this=22aed3a5, endpoint=bpm://getsubinfo, disposed=false} INFO 2008-07-07 00:45:39,641 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Connected: ProcessMessageDispatcher{this=22aed3a5, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:45:39,680 [jdbcConnector.receiver.2] org.jbpm.configuration.JbpmContextInfo: creating jbpm context with service factories '[tx, message, scheduler, logging, persistence, authentication]' DEBUG 2008-07-07 00:45:39,681 [jdbcConnector.receiver.2] org.jbpm.JbpmContext: creating org.jbpm.JbpmContext@782bbb7b DEBUG 2008-07-07 00:45:39,681 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceServiceFactory: creating persistence service DEBUG 2008-07-07 00:45:39,681 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: creating hibernate session DEBUG 2008-07-07 00:45:39,681 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: beginning hibernate transaction DEBUG 2008-07-07 00:45:39,683 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: begun hibernate transaction org.hibernate.transaction.JDBCTransaction@17cb0a16 WARN 2008-07-07 00:45:39,691 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: SQL Warning: 10000, SQLState: 01J01 WARN 2008-07-07 00:45:39,691 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: Database 'muleEmbeddedDB' not created, connection made to existing database instead. WARN 2008-07-07 00:45:39,693 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: SQL Warning: 10000, SQLState: 01J01 WARN 2008-07-07 00:45:39,693 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: Database 'muleEmbeddedDB' not created, connection made to existing database instead. DEBUG 2008-07-07 00:45:39,706 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'process-start' on 'ProcessDefinition(getsubinfo)' for 'Token(/)' DEBUG 2008-07-07 00:45:39,739 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'incomingSource' in 'TokenVariableMap7ea06d25' with value 'ServiceOrderRequests' DEBUG 2008-07-07 00:45:39,749 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'D', 'org.jbpm.context.exe.converter.DoubleToStringConverter' DEBUG 2008-07-07 00:45:39,749 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'C', 'org.jbpm.context.exe.converter.CharacterToStringConverter' DEBUG 2008-07-07 00:45:39,750 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'B', 'org.jbpm.context.exe.converter.BooleanToStringConverter' DEBUG 2008-07-07 00:45:39,754 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'Y', 'org.jbpm.context.exe.converter.BytesToByteArrayConverter' DEBUG 2008-07-07 00:45:39,755 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'A', 'org.jbpm.context.exe.converter.DateToLongConverter' DEBUG 2008-07-07 00:45:39,756 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'R', 'org.jbpm.context.exe.converter.SerializableToByteArrayConverter' DEBUG 2008-07-07 00:45:39,757 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'I', 'org.jbpm.context.exe.converter.IntegerToLongConverter' DEBUG 2008-07-07 00:45:39,757 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'H', 'org.jbpm.context.exe.converter.ShortToLongConverter' DEBUG 2008-07-07 00:45:39,758 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'G', 'org.jbpm.context.exe.converter.FloatToDoubleConverter' DEBUG 2008-07-07 00:45:39,759 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'F', 'org.jbpm.context.exe.converter.FloatToStringConverter' DEBUG 2008-07-07 00:45:39,759 [jdbcConnector.receiver.2] org.jbpm.db.hibernate.Converters: adding converter 'E', 'org.jbpm.context.exe.converter.ByteToLongConverter' DEBUG 2008-07-07 00:45:39,820 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'MULE_SESSION' in 'TokenVariableMap7ea06d25' with value 'SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQxYjg4NTBlLTRiYWQ tMTFkZC04OWU5LTI1YzAwOTQ5NzIzZg==' DEBUG 2008-07-07 00:45:39,820 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'MULE_CORRELATION_ID' in 'TokenVariableMap7ea06d25' with value '41b7c1bd-4bad-11dd-89e9-25c00949723f' DEBUG 2008-07-07 00:45:39,820 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'MULE_ENDPOINT' in 'TokenVariableMap7ea06d25' with value 'bpm://getsubinfo' DEBUG 2008-07-07 00:45:39,820 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'incoming' in 'TokenVariableMap7ea06d25' with value 'za.co.neotel.provision2.messages.ServiceOrder@54477b4e' DEBUG 2008-07-07 00:45:39,840 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'MULE_ORIGINATING_ENDPOINT' in 'TokenVariableMap7ea06d25' with value 'ServiceOrderRequests' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'incomingSource' in 'TokenVariableMap7ea06d25' to value 'ServiceOrderRequests' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'MULE_SESSION' in 'TokenVariableMap7ea06d25' to value 'SUQ9NDFiODg1MGUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQxYjg4NTBlLTRiYWQ tMTFkZC04OWU5LTI1YzAwOTQ5NzIzZg==' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'MULE_CORRELATION_ID' in 'TokenVariableMap7ea06d25' to value '41b7c1bd-4bad-11dd-89e9-25c00949723f' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'MULE_ENDPOINT' in 'TokenVariableMap7ea06d25' to value 'bpm://getsubinfo' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'incoming' in 'TokenVariableMap7ea06d25' to value 'za.co.neotel.provision2.messages.ServiceOrder@54477b4e' DEBUG 2008-07-07 00:45:39,841 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: update variable 'MULE_ORIGINATING_ENDPOINT' in 'TokenVariableMap7ea06d25' to value 'ServiceOrderRequests' DEBUG 2008-07-07 00:45:39,868 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'before-signal' on 'StartState(recvRequest)' for 'Token(/)' DEBUG 2008-07-07 00:45:39,893 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'node-leave' on 'StartState(recvRequest)' for 'Token(/)' DEBUG 2008-07-07 00:45:39,894 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'transition' on 'Transition(1af2f973)' for 'Token(/)' DEBUG 2008-07-07 00:45:39,964 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'node-enter' on 'State(receiveRequest)' for 'Token(/)' DEBUG 2008-07-07 00:45:40,036 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: executing action 'Action(21533b2c)' DEBUG 2008-07-07 00:45:40,036 [jdbcConnector.receiver.2] org.jbpm.graph.exe.Token: token[9863168] is locked by token[9863168] DEBUG 2008-07-07 00:45:40,074 [jdbcConnector.receiver.2] org.mule.transport.bpm.jbpm.actions.ValidateMessageSource: Executing action org.mule.transport.bpm.jbpm.actions.ValidateMessageSource from state "receiveRequest" DEBUG 2008-07-07 00:45:40,074 [jdbcConnector.receiver.2] org.mule.transport.bpm.jbpm.actions.ValidateMessageSource: Validating message source = ServiceOrderRequests, expected = ServiceOrderRequests DEBUG 2008-07-07 00:45:40,074 [jdbcConnector.receiver.2] org.jbpm.graph.exe.Token: token[9863168] is unlocked by token[9863168] DEBUG 2008-07-07 00:45:40,075 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: executing action 'Action(5f51d6cb)' DEBUG 2008-07-07 00:45:40,075 [jdbcConnector.receiver.2] org.jbpm.graph.exe.Token: token[9863168] is locked by token[9863168] DEBUG 2008-07-07 00:45:40,079 [jdbcConnector.receiver.2] org.mule.transport.bpm.jbpm.actions.StoreIncomingData: Executing action org.mule.transport.bpm.jbpm.actions.StoreIncomingData from state "receiveRequest" DEBUG 2008-07-07 00:45:40,088 [jdbcConnector.receiver.2] org.jbpm.context.exe.VariableContainer: create variable 'recvRequest' in 'TokenVariableMap7ea06d25' with value 'za.co.neotel.provision2.messages.ServiceOrder@54477b4e' DEBUG 2008-07-07 00:45:40,088 [jdbcConnector.receiver.2] org.jbpm.graph.exe.Token: token[9863168] is unlocked by token[9863168] DEBUG 2008-07-07 00:45:40,088 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: executing action 'Action(75ecda50)' DEBUG 2008-07-07 00:45:40,088 [jdbcConnector.receiver.2] org.jbpm.graph.exe.Token: token[9863168] is locked by token[9863168] DEBUG 2008-07-07 00:45:40,092 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'node-leave' on 'State(receiveRequest)' for 'Token(/)' DEBUG 2008-07-07 00:45:40,092 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'transition' on 'Transition(18170f98)' for 'Token(/)' DEBUG 2008-07-07 00:45:40,094 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: event 'node-enter' on 'State(sendMessage)' for 'Token(/)' DEBUG 2008-07-07 00:45:40,128 [jdbcConnector.receiver.2] org.jbpm.graph.def.GraphElement: executing action 'action[sendRequest]' DEBUG 2008-07-07 00:45:40,133 [jdbcConnector.receiver.2] org.mule.transport.bpm.jbpm.actions.SendMuleEventAndContinue: Executing action org.mule.transport.bpm.jbpm.actions.SendMuleEventAndContinue from state "sendMessage" DEBUG 2008-07-07 00:45:40,254 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageReceiver: Executing process is sending an event (synchronously) to Mule endpoint = HLRSyncSvc DEBUG 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageReceiver: Message Received from: bpm://getsubinfo DEBUG 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: There is no session id on the request using key: ID. Generating new session id: 422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event to service: FromBPMS event is: MuleEvent: 422b6b26-4bad-11dd-89e9-25c00949723f, sync=true, stop processing=false, DefaultInboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='ProcessEngine', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: Service: FromBPMS has received synchronous event on: bpm://getsubinfo DEBUG 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: FromBPMS : got proxy for 422b6b26-4bad-11dd-89e9-25c00949723f = org.mule.component.DefaultJavaComponent component for: FromBPMS INFO 2008-07-07 00:45:40,255 [jdbcConnector.receiver.2] org.mule.component.simple.LogComponent: **************************************************************************** **** * Message received in service: FromBPMS. Content is: '26' * **************************************************************************** **** DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Applying transformer TransformerTemplate (org.mule.transformer.TransformerTemplate) DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object before transform: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAd apter@6bfe3238{id=422b6b24-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=9830400, correlationGroup=-1... DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object after transform: 26 DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: CorrelationId is already set to '9830400' , not setting it again DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Message being sent to: vm://hlr.requests DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAd apter@6bfe3238{id=422b6b24-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=9830400, correlationGroup=-1, correlationSeq=-1, encoding=UTF-8, exceptionPayload=null, properties=Properites{invocation:{}, inbound:{}, outbound:{MULE_BPM_STARTED=Mon Jul 07 00:45:39 SAST 2008, MULE_ORIGINATING_ENDPOINT=ProcessEngine, MULE_BPM_PROCESS_TYPE=getsubinfo, MULE_BPM_PROCESS_ID=9830400, MULE_CORRELATION_ID=9830400, MULE_BPM_ENDPOINT=HLRSyncSvc}, session:{}, }} DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: MuleSession has received synchronous event on endpoint: DefaultOutboundEndpoint{endpointUri=vm://hlr.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='HLRSyncSvc', properties={method=getInfo}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Creating event with data: java.lang.String, for endpoint: DefaultOutboundEndpoint{endpointUri=vm://hlr.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='HLRSyncSvc', properties={method=getInfo}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event: MuleEvent: 422b9237-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultOutboundEndpoint{endpointUri=vm://hlr.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='HLRSyncSvc', properties={method=getInfo}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=true, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,256 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowing a dispatcher for endpoint: vm://hlr.requests DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed a dispatcher for endpoint: vm://hlr.requests = VMMessageDispatcher{this=7d9331eb, endpoint=vm://hlr.requests, disposed=false} DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed dispatcher: VMMessageDispatcher{this=7d9331eb, endpoint=vm://hlr.requests, disposed=false} DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connecting: VMMessageDispatcher{this=7d9331eb, endpoint=vm://hlr.requests, disposed=false} INFO 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connected: VMMessageDispatcher{this=7d9331eb, endpoint=vm://hlr.requests, disposed=false} DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Looking up vm receiver for address: vm://hlr.requests DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Found exact receiver match on endpointUri: vm://hlr.requests DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:40,257 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageReceiver: Message Received from: vm://hlr.requests DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Applying transformer Base64Decoder (org.mule.transformer.codec.Base64Decoder) DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object before transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNm DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object after transform: {73,68,61,52,50,50,98,54,98,50,53,45,52,98,97,100,45,49,49,100,100,45,56,57, 101,57,45,50,53,99,48,48,57,52,57,55,50,51,102} DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: The transformed object is of expected type. Type is: byte[] DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Parsing session header: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Added MuleSession variable: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Got session with id: 422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event to service: HLRService event is: MuleEvent: 422be059-4bad-11dd-89e9-25c00949723f, sync=true, stop processing=false, DefaultInboundEndpoint{endpointUri=vm://hlr.requests, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='HLRSyncSvc', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: Service: HLRService has received synchronous event on: vm://hlr.requests DEBUG 2008-07-07 00:45:40,258 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: HLRService : got proxy for 422be059-4bad-11dd-89e9-25c00949723f = org.mule.component.DefaultJavaComponent component for: HLRService INFO 2008-07-07 00:45:40,261 [jdbcConnector.receiver.2] za.co.neotel.provision2.common.Settings: Initialising settings DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.transport.tcp.TcpMessageReceiver: Message Received from: tcp://localhost:7777 DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.DefaultMuleSession: There is no session id on the request using key: ID. Generating new session id: 422f14ab-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.DefaultMuleSession: dispatching event to service: HLRTestServer, event is: MuleEvent: 422f14ac-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultInboundEndpoint{endpointUri=tcp://localhost:7777, connector=TcpConnector{this=7f371a59, started=true, initialised=true, name='TCPConn', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[tcp], serviceOverrides=null}, transformer=[], name='SocketToHLR', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.model.seda.SedaService: Service: HLRTestServer has received asynchronous event on: tcp://localhost:7777 DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.model.seda.SedaService: Service: HLRTestServer has received asynchronous event on: tcp://localhost:7777 DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.registry.TransientRegistry: lookupObject: key=_muleQueueManager scope=2 DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.registry.TransientRegistry: Failed to find object in Registry ID: org.mule.Registry.Transient DEBUG 2008-07-07 00:45:40,279 [TCPConn.receiver.2] org.mule.config.spring.SpringRegistry: lookupObject: key=_muleQueueManager scope=2 DEBUG 2008-07-07 00:45:40,280 [TCPConn.receiver.2] org.mule.model.seda.SedaService: Service HLRTestServer putting event on queue HLRTestServer: MuleEvent: 422f14ac-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultInboundEndpoint{endpointUri=tcp://localhost:7777, connector=TcpConnector{this=7f371a59, started=true, initialised=true, name='TCPConn', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[tcp], serviceOverrides=null}, transformer=[], name='SocketToHLR', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:45:40,280 [HLRTestServer.1] org.mule.model.seda.SedaService: Service: HLRTestServer dequeued event on: tcp://localhost:7777 DEBUG 2008-07-07 00:45:40,281 [HLRTestServer.2] org.mule.DefaultMuleMessage: new copy of message for Thread[HLRTestServer.2,5,main] DEBUG 2008-07-07 00:45:40,282 [HLRTestServer.2] org.mule.transformer.TransformerTemplate: Applying transformer TransformerTemplate (org.mule.transformer.TransformerTemplate) DEBUG 2008-07-07 00:45:40,282 [HLRTestServer.2] org.mule.transformer.TransformerTemplate: Object before transform: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAd apter@3f9261de{id=422f14aa-4bad-11dd-89e9-25c00949723f, payload=org.mule.transport.tcp.TcpMessageReceiver$TcpWorker$1, corre... DEBUG 2008-07-07 00:45:40,282 [HLRTestServer.2] org.mule.transformer.TransformerTemplate: Object after transform: MuleEvent: 422f14ac-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultInboundEndpoint{endpointUri=tcp://localhost:7777, connector=TcpConnector{this=7f371a59, started=true, initial... DEBUG 2008-07-07 00:45:40,282 [HLRTestServer.2] org.mule.transformer.TransformerTemplate: The transformed object is of expected type. Type is: DefaultMuleEventContext INFO 2008-07-07 00:45:40,294 [jdbcConnector.receiver.2] za.co.neotel.provision2.node.hlr.HLRConnectSvc: Receive process with processIdnull INFO 2008-07-07 00:45:40,294 [Thread-3] za.co.neotel.provision2.node.hlr.HuaweiHLRIO: Client@433c8540Server Found ERROR 2008-07-07 00:45:40,305 [Thread-3] za.co.neotel.provision2.node.hlr.HuaweiHLRIO: Client:Client@433c8540 sending login packet. ERROR 2008-07-07 00:46:00,310 [Thread-3] za.co.neotel.provision2.node.hlr.HuaweiHLRIO: Client:Client@433c8540 sending login packet. *** Responsenull Request received at 1215384340294 Response received at 1215384360327 Response received at -20033 ERROR 2008-07-07 00:46:00,327 [jdbcConnector.receiver.2] za.co.neotel.provision2.node.hlr.HuaweiHLRIO: Client:Client@433c8540Polling loop stopping DEBUG 2008-07-07 00:46:00,327 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: CorrelationId is already set to '9830400' , not setting it again DEBUG 2008-07-07 00:46:00,327 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Message being sent to: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAd apter@5fcbc39b{id=422b6b24-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=9830400, correlationGroup=-1, correlationSeq=-1, encoding=UTF-8, exceptionPayload=null, properties=Properites{invocation:{}, inbound:{}, outbound:{MULE_BPM_PROCESS_ID=9830400, MULE_CORRELATION_ID=9830400, MULE_ENDPOINT=vm://hlr.requests, MULE_BPM_STARTED=Mon Jul 07 00:45:39 SAST 2008, MULE_ORIGINATING_ENDPOINT=HLRSyncSvc, MULE_BPM_PROCESS_TYPE=getsubinfo, MULE_BPM_ENDPOINT=HLRSyncSvc}, session:{}, }} DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: MuleSession has received synchronous event on endpoint: DefaultOutboundEndpoint{endpointUri=vm://customer.hlrresponse, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderHLRResponse', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Creating event with data: java.lang.String, for endpoint: DefaultOutboundEndpoint{endpointUri=vm://customer.hlrresponse, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderHLRResponse', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event: MuleEvent: 4e2250be-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultOutboundEndpoint{endpointUri=vm://customer.hlrresponse, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderHLRResponse', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding property to session header: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c009497 23f DEBUG 2008-07-07 00:46:00,328 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c009497 23f DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQyMmI2YjI1LTRiYWQt MTFkZC04OWU5LTI1YzAwOTQ5NzIzZg== DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowing a dispatcher for endpoint: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed a dispatcher for endpoint: vm://customer.hlrresponse = VMMessageDispatcher{this=3a97263f, endpoint=vm://customer.hlrresponse, disposed=false} DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Borrowed dispatcher: VMMessageDispatcher{this=3a97263f, endpoint=vm://customer.hlrresponse, disposed=false} DEBUG 2008-07-07 00:46:00,329 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connecting: VMMessageDispatcher{this=3a97263f, endpoint=vm://customer.hlrresponse, disposed=false} INFO 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageDispatcher: Connected: VMMessageDispatcher{this=3a97263f, endpoint=vm://customer.hlrresponse, disposed=false} DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Looking up vm receiver for address: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transport.vm.VMConnector: Found exact receiver match on endpointUri: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding property to session header: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c009497 23f DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c009497 23f DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQyMmI2YjI1LTRiYWQt MTFkZC04OWU5LTI1YzAwOTQ5NzIzZg== DEBUG 2008-07-07 00:46:00,330 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.transport.vm.VMMessageReceiver: Message Received from: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Applying transformer Base64Decoder (org.mule.transformer.codec.Base64Decoder) DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object before transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQyMmI2YjI1LTRiYWQt MTFkZC04OWU5LTI1YzAwOTQ5NzIzZg== DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: Object after transform: {73,68,61,52,50,50,98,54,98,50,53,45,52,98,97,100,45,49,49,100,100,45,56,57, 101,57,45,50,53,99,48,48,57,52,57,55,50,51,102,59,73,68,61,52,50,50,98,54,98 ,50 [..]} DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Decoder: The transformed object is of expected type. Type is: byte[] DEBUG 2008-07-07 00:46:00,331 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Parsing session header: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c009497 23f DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Added MuleSession variable: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Added MuleSession variable: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Got session with id: 422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event to service: ToBPMS event is: MuleEvent: 4e22ed00-4bad-11dd-89e9-25c00949723f, sync=true, stop processing=false, DefaultInboundEndpoint{endpointUri=vm://customer.hlrresponse, connector=VMConnector{this=328d5485, started=true, initialised=true, name='syncVm', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[vm], serviceOverrides=null}, transformer=[], name='ServiceOrderHLRResponse', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: Service: ToBPMS has received synchronous event on: vm://customer.hlrresponse DEBUG 2008-07-07 00:46:00,332 [jdbcConnector.receiver.2] org.mule.model.seda.SedaService: ToBPMS : got proxy for 4e22ed00-4bad-11dd-89e9-25c00949723f = org.mule.component.DefaultJavaComponent component for: ToBPMS INFO 2008-07-07 00:46:00,333 [jdbcConnector.receiver.2] org.mule.component.simple.LogComponent: **************************************************************************** **** * Message received in service: ToBPMS. Content is: 'Error - Request Timed Out' * **************************************************************************** **** DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Applying transformer TransformerTemplate (org.mule.transformer.TransformerTemplate) DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object before transform: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAd apter@7a5e832b{id=422b6b24-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=9830400, correlationGroup=-1... DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: Object after transform: Error - Request Timed Out DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.transformer.TransformerTemplate: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: CorrelationId is already set to '9830400' , not setting it again DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: Message being sent to: bpm://getsubinfo DEBUG 2008-07-07 00:46:00,334 [jdbcConnector.receiver.2] org.mule.routing.outbound.FilteringOutboundRouter: org.mule.transport.DefaultMessageAdapter/org.mule.transport.DefaultMessageAd apter@7a5e832b{id=422b6b24-4bad-11dd-89e9-25c00949723f, payload=java.lang.String, correlationId=9830400, correlationGroup=-1, correlationSeq=-1, encoding=UTF-8, exceptionPayload=null, properties=Properites{invocation:{}, inbound:{}, outbound:{MULE_BPM_PROCESS_ID=9830400, MULE_CORRELATION_ID=9830400, MULE_ENDPOINT=vm://customer.hlrresponse, MULE_BPM_STARTED=Mon Jul 07 00:45:39 SAST 2008, MULE_ORIGINATING_ENDPOINT=ServiceOrderHLRResponse, MULE_BPM_PROCESS_TYPE=getsubinfo, MULE_BPM_ENDPOINT=HLRSyncSvc}, session:{}, }} DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: MuleSession has received synchronous event on endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: Creating event with data: java.lang.String, for endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.DefaultMuleSession: sending event: MuleEvent: 4e236231-4bad-11dd-89e9-25c00949723f, sync=false, stop processing=false, DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8} DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding property to session header: ID=422b6b25-4bad-11dd-89e9-25c00949723f DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.MuleSessionHandler: Adding session header to message: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c009497 23f DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Applying transformer Base64Encoder (org.mule.transformer.codec.Base64Encoder) DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object before transform: ID=422b6b25-4bad-11dd-89e9-25c00949723f;ID=422b6b25-4bad-11dd-89e9-25c009497 23f DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: Object after transform: SUQ9NDIyYjZiMjUtNGJhZC0xMWRkLTg5ZTktMjVjMDA5NDk3MjNmO0lEPTQyMmI2YjI1LTRiYWQt MTFkZC04OWU5LTI1YzAwOTQ5NzIzZg== DEBUG 2008-07-07 00:46:00,335 [jdbcConnector.receiver.2] org.mule.transformer.codec.Base64Encoder: The transformed object is of expected type. Type is: String DEBUG 2008-07-07 00:46:00,336 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowing a dispatcher for endpoint: bpm://getsubinfo DEBUG 2008-07-07 00:46:00,336 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowed a dispatcher for endpoint: bpm://getsubinfo = ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:46:00,336 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Borrowed dispatcher: ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:46:00,336 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Connecting: ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} INFO 2008-07-07 00:46:00,336 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Connected: ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:46:00,337 [jdbcConnector.receiver.2] org.jbpm.configuration.JbpmContextInfo: creating jbpm context with service factories '[tx, message, scheduler, logging, persistence, authentication]' DEBUG 2008-07-07 00:46:00,337 [jdbcConnector.receiver.2] org.jbpm.JbpmContext: creating org.jbpm.JbpmContext@2bbd83d DEBUG 2008-07-07 00:46:00,337 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceServiceFactory: creating persistence service DEBUG 2008-07-07 00:46:00,337 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: creating hibernate session DEBUG 2008-07-07 00:46:00,337 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: beginning hibernate transaction DEBUG 2008-07-07 00:46:00,339 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: begun hibernate transaction org.hibernate.transaction.JDBCTransaction@441944ae DEBUG 2008-07-07 00:46:00,593 [jdbcConnector.receiver.2] org.jbpm.JbpmContext: closing jbpmContext org.jbpm.JbpmContext@2bbd83d DEBUG 2008-07-07 00:46:00,593 [jdbcConnector.receiver.2] org.jbpm.svc.Services: closing service 'persistence': org.jbpm.persistence.db.DbPersistenceService@ea25c1 DEBUG 2008-07-07 00:46:00,593 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: rolling back hibernate transaction DEBUG 2008-07-07 00:46:00,594 [jdbcConnector.receiver.2] org.jbpm.persistence.db.DbPersistenceService: closing hibernate session WARN 2008-07-07 00:46:00,594 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: SQL Warning: 10000, SQLState: 01J01 WARN 2008-07-07 00:46:00,594 [jdbcConnector.receiver.2] org.hibernate.util.JDBCExceptionReporter: Database 'muleEmbeddedDB' not created, connection made to existing database instead. DEBUG 2008-07-07 00:46:00,594 [jdbcConnector.receiver.2] org.jbpm.svc.Services: closing service 'tx': org.jbpm.tx.TxService@c54a25f DEBUG 2008-07-07 00:46:00,594 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Disconnecting: ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} INFO 2008-07-07 00:46:00,595 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessMessageDispatcher: Disconnected: ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=false} DEBUG 2008-07-07 00:46:00,601 [jdbcConnector.receiver.2] org.mule.transport.bpm.ProcessConnector: Returning dispatcher for endpoint: bpm://getsubinfo = ProcessMessageDispatcher{this=52f5bad0, endpoint=bpm://getsubinfo, disposed=true} ERROR 2008-07-07 00:46:00,653 [jdbcConnector.receiver.2] org.mule.service.DefaultServiceExceptionStrategy: **************************************************************************** **** Message : Failed to route event via endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8}. Message payload is of type: String Type : org.mule.api.transport.DispatchException Code : MULE_ERROR-42999 JavaDoc : http://mule.mulesource.org/docs/apidocs/org/mule/api/transport/DispatchExcep tion.html Payload : Error - Request Timed Out **************************************************************************** **** Exception stack is: 1. No row with the given identifier exists: [org.jbpm.graph.exe.ProcessInstance#9830400] (org.hibernate.ObjectNotFoundException) org.hibernate.impl.SessionFactoryImpl$1:377 (null) 2. Failed to route event via endpoint: DefaultOutboundEndpoint{endpointUri=bpm://getsubinfo, connector=ProcessConnector{this=27d08e21, started=true, initialised=true, name='jBpmConnector', disposed=false, numberOfConcurrentTransactedReceivers=4, createMultipleTransactedReceivers=true, connected=true, supportedProtocols=[bpm], serviceOverrides=null}, transformer=[], name='endpoint.bpm.getsubinfo', properties={}, transactionConfig=Transaction{factory=null, action=NONE, timeout=0}, filter=null, deleteUnacceptedMessages=false, securityFilter=null, synchronous=false, initialState=started, remoteSync=false, remoteSyncTimeout=3000, endpointEncoding=UTF-8}. Message payload is of type: String (org.mule.api.transport.DispatchException) org.mule.transport.AbstractMessageDispatcher:188 (http://mule.mulesource.org/docs/apidocs/org/mule/api/transport/DispatchExce ption.html) **************************************************************************** **** Root Exception stack trace: org.hibernate.ObjectNotFoundException: No row with the given identifier exists: [org.jbpm.graph.exe.ProcessInstance#9830400] at org.hibernate.impl.SessionFactoryImpl$1.handleEntityNotFound(SessionFactoryI mpl.java:377) ============================================================================ =========== Config: <?xml version="1.0" encoding="UTF-8"?> <!--<process-definition xmlns="urn:jbpm.org:jpdl-3.2" name="getsubinfo">--> <process-definition name="getsubinfo"> <start-state name="recvRequest"> <transition to="receiveRequest"></transition> </start-state> <state name="receiveRequest"> <event type="node-enter"> <action class="org.mule.transport.bpm.jbpm.actions.ValidateMessageSource"> <expectedSource>ServiceOrderRequests</expectedSource> </action> <action class="org.mule.transport.bpm.jbpm.actions.StoreIncomingData"> <variable>recvRequest</variable> </action> <action class="org.mule.transport.bpm.jbpm.actions.Continue"> </action> </event> <transition to="sendMessage"></transition> </state> <state name="sendMessage"> <event type="node-enter"> <action name="sendRequest" class="org.mule.transport.bpm.jbpm.actions.SendMuleEventAndContinue"> <!-- This is the variable we stored in the previous step --> <payloadSource>recvRequest.orderId</payloadSource> <endpoint>HLRSyncSvc</endpoint> </action> </event> <transition to="ReceiveInfo"></transition> </state> <state name="ReceiveInfo"> <event type="node-enter"> <action class="org.mule.providers.bpm.jbpm.actions.StoreIncomingData"> <variable> hlrResponse </variable> <variable> result </variable> <variable> response </variable> </action> </event> <transition to="decision1"></transition> </state> <decision name="decision1" expression="result='Error - Request Timed Out'"> <transition to="end-state1" name="false"></transition> <transition to="end-state2" name="true"></transition> </decision> <end-state name="end-state1"></end-state> <end-state name="end-state2"></end-state> </process-definition> --------- Process definition file: <?xml version="1.0" encoding="UTF-8"?> <mule xmlns="http://www.mulesource.org/schema/mule/core/2.0" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:spring="http://www.springframework.org/schema/beans" xmlns:context="http://www.springframework.org/schema/context" xmlns:bpm="http://www.mulesource.org/schema/mule/bpm/2.0" xmlns:vm="http://www.mulesource.org/schema/mule/vm/2.0" xmlns:jms="http://www.mulesource.org/schema/mule/jms/2.0" xmlns:jdbc="http://www.mulesource.org/schema/mule/jdbc/2.0" xmlns:tcp="http://www.mulesource.org/schema/mule/tcp/2.0" xsi:schemaLocation=" http://www.springframework.org/schema/beans http://www.springframework.org/schema/beans/spring-beans-2.0.xsd http://www.springframework.org/schema/context http://www.springframework.org/schema/context/spring-context-2.5.xsd http://www.mulesource.org/schema/mule/core/2.0 http://www.mulesource.org/schema/mule/core/2.0/mule.xsd http://www.mulesource.org/schema/mule/bpm/2.0 http://www.mulesource.org/schema/mule/bpm/2.0/mule-bpm.xsd http://www.mulesource.org/schema/mule/vm/2.0 http://www.mulesource.org/schema/mule/vm/2.0/mule-vm.xsd http://www.mulesource.org/schema/mule/jms/2.0 http://www.mulesource.org/schema/mule/jms/2.0/mule-jms.xsd http://www.mulesource.org/schema/mule/jdbc/2.0 http://www.mulesource.org/schema/mule/jdbc/2.0/mule-jdbc.xsd http://www.mulesource.org/schema/mule/tcp/2.0 http://www.mulesource.org/schema/mule/tcp/2.0/mule-tcp.xsd"> <bpm:connector name="jBpmConnector" bpms-ref="jbpm" /> <vm:connector name="syncVm" /> <vm:connector name="asyncVm" queueEvents="true" /> <tcp:connector name="TCPConn"><tcp:streaming-protocol/> </tcp:connector> <jdbc:connector name="jdbcConnector" dataSource-ref="serviceorder-datasource" pollingFrequency="120000"> <!-- <jdbc:query key="getServiceOrders" value="SELECT ID, PRODUCT_ID,ORDERID,STATUS,CDN,ESN,IMSI, ORDER_PARAMETERS,TYPE,ERROR_CODE,ERROR_DESCRIPTION FROM SUGAR.SERVICEORDER " /> --> <jdbc:query key="getServiceOrders" value="SELECT ORDER_PARAMETERS FROM SUGAR.SERVICEORDER LIMIT 0,1" /> <!-- <property name="getTest.ack" value="UPDATE IGOR_SO SET STATUS = 999 where ORDERID = ${orderid}"/> --> </jdbc:connector> <!-- We could use JMS instead of VM to make the example more realistic. <jms:activemq-connector name="jmsConnector" /> --> <!-- The Provisioning is driven by a Process Engine --> <endpoint name="ProcessEngine" address="bpm://getsubinfo" /> <!-- Customer loan requests to the Loan Broker --> <vm:endpoint name="ServiceOrderRequests" path="customer.requests" connector-ref="syncVm" /> <vm:endpoint name="HLRSyncSvc" path="hlr.requests" connector-ref="syncVm" /> <vm:endpoint name="ServiceOrderHLRResponse" path="customer.hlrresponse" connector-ref="syncVm" /> <tcp:endpoint name="SocketToHLR" connector-ref="TCPConn" host="localhost" port="7777" /> <!-- agents --> <!-- connectors --> <model name="loan-broker"> <!-- *************************************************** Process Engine *************************************************** --> <service name="ServiceOrderPoller"> <inbound> <jdbc:inbound-endpoint queryKey="getServiceOrders?method=getOrderParameters" synchronous="true"> <object-to-string-transformer /> </jdbc:inbound-endpoint> </inbound> <!-- <echo-component /> --> <component class="za.co.neotel.provision2.mule.ServiceOrderStripper"> </component> <outbound> <filtering-router> <outbound-endpoint ref="ServiceOrderRequests" synchronous="true" /> </filtering-router> </outbound> </service> <!-- Messages sent to the process engine --> <service name="ToBPMS"> <inbound> <!-- Incoming message = ServiceOrderRequests --> <inbound-endpoint ref="ServiceOrderRequests" /> <!-- Incoming message = ServiceOrderHLRResponse --> <inbound-endpoint ref="ServiceOrderHLRResponse" /> </inbound> <echo-component /> <outbound> <filtering-router> <outbound-endpoint address="bpm://getsubinfo" /> </filtering-router> </outbound> </service> <!-- Messages generated from the process engine --> <service name="FromBPMS"> <inbound> <inbound-endpoint ref="ProcessEngine" /> </inbound> <echo-component /> <outbound> <filtering-router> <!-- Outgoing message = HLRRequest --> <outbound-endpoint ref="HLRSyncSvc" synchronous="true" > <property key="method" value="getInfo" /> </outbound-endpoint> </filtering-router> </outbound> </service> <!-- *************************************************** Services *************************************************** --> <!-- The HLR Service receives requests for the HLR and sends them through --> <service name="HLRService"> <inbound> <inbound-endpoint ref="HLRSyncSvc" /> </inbound> <component class="za.co.neotel.provision2.node.hlr.HLRConnectSvc"> </component> <outbound> <filtering-router> <outbound-endpoint ref="ServiceOrderHLRResponse" /> </filtering-router> </outbound> </service> <service name="HLRTestServer" initialState="started"> <inbound> <inbound-endpoint ref="SocketToHLR" /> </inbound> <component > <singleton-object class="za.co.neotel.simulator.TestHLR" /> </component> </service> </model> <!-- *************************************************** BPMS Configuration *************************************************** --> <!-- <context:property-placeholder location="derby.properties" /> --> <spring:bean id="propertyConfigurer" class="org.springframework.beans.factory.config.PropertyPlaceholderConfigure r"> <spring:property name="locations"> <spring:list> <spring:value>derby.properties</spring:value> </spring:list> </spring:property> <spring:property name="placeholderPrefix" value="$[" /> <spring:property name="placeholderSuffix" value="]" /> </spring:bean> <!-- BPMS object for Mule's BPM Connector --> <spring:bean id="jbpm" class="org.mule.transport.bpm.jbpm.Jbpm" destroy-method="destroy"> <spring:property name="jbpmConfiguration"> <spring:ref local="jbpmConfig" /> </spring:property> </spring:bean> <!-- jBPM Configuration --> <spring:bean id="jbpmConfig" class="org.springmodules.workflow.jbpm31.LocalJbpmConfigurationFactoryBean"> <spring:property name="sessionFactory"> <spring:ref local="jbpmSessionFactory" /> </spring:property> <spring:property name="configuration"> <spring:value>jbpm.cfg.xml</spring:value> </spring:property> <spring:property name="processDefinitions"> <spring:list> <spring:bean id="provisioningBPM" class="org.springmodules.workflow.jbpm31.definition.ProcessDefinitionFactory Bean"> <spring:property name="definitionLocation"> <spring:value> /getsubinfo/processdefinition.xml </spring:value> </spring:property> </spring:bean> </spring:list> </spring:property> <spring:property name="createSchema"> <spring:value>false</spring:value> </spring:property> </spring:bean> <!-- JBPM Hibernate SessionFactory --> <spring:bean id="jbpmSessionFactory" class="org.springframework.orm.hibernate3.LocalSessionFactoryBean"> <spring:property name="dataSource"> <spring:ref local="jbpmDataSource" /> </spring:property> <spring:property name="mappingLocations"> <spring:value> classpath*:/org/jbpm/**/*.hbm.xml </spring:value> </spring:property> <spring:property name="typeDefinitions"> <spring:ref local="jbpmTypes" /> </spring:property> <spring:property name="hibernateProperties"> <spring:props> <spring:prop key="hibernate.dialect"> org.hibernate.dialect.DerbyDialect </spring:prop> <spring:prop key="hibernate.cache.provider_class"> org.hibernate.cache.HashtableCacheProvider </spring:prop> <!-- Create/update the database tables automatically when the JVM starts up --> <spring:prop key="hibernate.hbm2ddl.auto"> update </spring:prop> </spring:props> </spring:property> </spring:bean> <!-- JBPM Datasource --> <spring:bean id="jbpmDataSource" class="org.springframework.jdbc.datasource.DriverManagerDataSource"> <spring:property name="driverClassName"> <spring:value> org.apache.derby.jdbc.EmbeddedDriver </spring:value> </spring:property> <spring:property name="url"> <spring:value>$[database.connection]</spring:value> </spring:property> </spring:bean> <spring:bean name="serviceorder-datasource" class="org.springframework.jdbc.datasource.DriverManagerDataSource"> <spring:property name="driverClassName"> <spring:value>com.mysql.jdbc.Driver</spring:value> </spring:property> <spring:property name="url" value="jdbc:-----------------" /> <spring:property name="username"> <spring:value>$[jdbc.mysql.user]</spring:value> </spring:property> <spring:property name="password"> <spring:value>$[jdbc.mysql.pwd]</spring:value> </spring:property> </spring:bean> <!-- JBPM data types --> <spring:bean id="jbpmTypes" class="org.springframework.orm.hibernate3.TypeDefinitionBean"> <spring:property name="typeName"> <spring:value>string_max</spring:value> </spring:property> <spring:property name="typeClass"> <spring:value>org.jbpm.db.hibernate.StringMax</spring:value> </spring:property> </spring:bean> </mule> --------------------------------------------------------------------- To unsubscribe from this list, please visit: http://xircles.codehaus.org/manage_email --------------------------------------------------------------------- To unsubscribe from this list, please visit: http://xircles.codehaus.org/manage_email |
|
|
|
| Free embeddable forum powered by Nabble | Forum Help |