RE: JBPM process not being saved as expected

Hi,

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