unreliable delivery of respons messages
---------------------------------------
Key: ODE-80
URL: http://issues.apache.org/jira/browse/ODE-80
Project: Apache Ode
Issue Type: Bug
Components: JBI Integration
Environment: Windows XP, j2sdk1.5.0_06, ServiceMix 3.0 Release,
ode-jbi-2.0-SNAPSHOT-installer.zip (revision 474898)
Reporter: Matthias von Arx
With the two SA'a attached to ODE-79 about 50% of the response messages will
not be delivered to the caller. In case of fail the following lines were
written to the log file:
15:15:14,420 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
mpl$ExecutionQueueOutputStream 694 | ReplacmentMap:
getReplacement(OFlow#64-invoke) = [EMAIL PROTECTED]
15:15:14,420 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
mpl$ExecutionQueueOutputStream 694 | ReplacmentMap: getReplacement({OScope
'__PROCESS_SCOPE:CriminalRegisterProcess' id=3}) = [EMAIL PROTECTED]
15:15:14,430 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
mpl$ExecutionQueueOutputStream 694 | ReplacmentMap:
getReplacement(OSequence#36-__unnamed:{http://schemas.xmlsoap.org/ws/2004/03/business-process/[EMAIL
PROTECTED]) = [EMAIL PROTECTED]
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
mpl$ExecutionQueueOutputStream 694 | ReplacmentMap: getReplacement({OAssign :
assign3, joinCondition=null}) = [EMAIL PROTECTED]
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
mpl$ExecutionQueueOutputStream 694 | ReplacmentMap:
getReplacement(OReply#86-end) = [EMAIL PROTECTED]
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
mpl$ExecutionQueueOutputStream 694 | ReplacmentMap: getReplacement({OScope
'invokeFederalRegister' id=65}) = [EMAIL PROTECTED]
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
mpl$ExecutionQueueOutputStream 694 | ReplacmentMap:
getReplacement(OInvoke#66-invokeFederalRegister) = [EMAIL PROTECTED]
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
mpl$ExecutionQueueOutputStream 694 | ReplacmentMap: getReplacement({Variable
__PROCESS_SCOPE:CriminalRegisterProcess.federalResponse:OMessageVarType#22}) =
[EMAIL PROTECTED]
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
mpl$ExecutionQueueOutputStream 694 | ReplacmentMap: getReplacement(OProcess#0)
= [EMAIL PROTECTED]
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 339 | GC Channel: {CFRAME
ParentScopeChannel:null#8 refCount=0, msgs=0, objs=0}
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 339 | GC Channel: {CFRAME
TerminationChannel:null#11 refCount=0, msgs=0, objs=0}
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 339 | GC Channel: {CFRAME
ParentScopeChannel:null#7 refCount=0, msgs=0, objs=0}
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 339 | GC Channel: {CFRAME
ParentScopeChannel:null#12 refCount=0, msgs=0, objs=0}
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 339 | GC Channel: {CFRAME
ParentScopeChannel:null#13 refCount=0, msgs=0, objs=0}
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 339 | GC Channel: {CFRAME
PickResponseChannel:null#9 refCount=0, msgs=0, objs=0}
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 339 | GC Channel: {CFRAME
TerminationChannel:null#6 refCount=0, msgs=0, objs=0}
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 339 | GC Channel: {CFRAME
ParentScopeChannel:null#10 refCount=0, msgs=0, objs=0}
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 349 | Writing Channel: {CFRAME
ParentScopeChannel:null#15 refCount=0, msgs=0, objs=1}
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 349 | Writing Channel: {CFRAME
ParentScopeChannel:null#4 refCount=0, msgs=0, objs=1}
15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 349 | Writing Channel: {CFRAME
ParentScopeChannel:null#19 refCount=0, msgs=0, objs=1}
15:15:14,450 | DEBUG | Thread-19 | SedaQueue |
.jbi.nmr.flow.seda.SedaQueue$1 225 | [EMAIL PROTECTED] dequeued exchange:
InOut[
id: ID:chav548c-1815-1163599444703-4:25
status: Done
role: provider
service:
{http://jbi.exercise.com/xml/ns/services/federalcriminalregister}FederalCriminalRegisterService
endpoint: JbiEndpoint
operation:
{http://jbi.exercise.com/xml/ns/services/federalcriminalregister}getCriminalRecords
in: <?xml version="1.0" encoding="UTF-8"?><getCriminalRecords
xmlns="http://jbi.exercise.com/xml/ns/services/federalcriminalregister";
xmlns:ns2="http://jbi.exercise.com/xml/ns/services/federalcriminalregister";>
<ns2:principalId>2.2.2.2</ns2:principalId>
</getCriminalRecords>
out: <?xml version="1.0" encoding="UTF-8"?><getCriminalRecordsResponse
xmlns="http://jbi.exercise.com/xml/ns/services/federalcriminalregister";><criminalRecords><ns1:CriminalRecord
xmlns:ns1="http://federalcriminalregister.services.jbi.exercise.com";><dateOfArrest
xmlns="http://federalcriminalregister.services.jbi.exercise.com";>1990-04-20T00:00:00+02:00</dateOfArrest><dateOfDischarge
xmlns="http://federalcriminalregister.services.jbi.exercise.com";>1991-03-20T00:00:00+01:00</dateOfDischarge><dateOfIncident
xmlns="http://federalcriminalregister.services.jbi.exercise.com";>1990-03-18T00:00:00+01:00</dateOfIncident><kindOfIncident
xmlns="http://federalcriminalregister.services.jbi.exercise.com";>Theft</kindOfIncident></ns1:CriminalRecord></criminalRecords></getCriminalRecordsResponse>
]
15:15:14,450 | DEBUG | Thread-19 | DeliveryChannelImpl |
.messaging.DeliveryChannelImpl 579 | Processing inbound exchange: InOut[
id: ID:chav548c-1815-1163599444703-4:25
status: Done
role: provider
service:
{http://jbi.exercise.com/xml/ns/services/federalcriminalregister}FederalCriminalRegisterService
endpoint: JbiEndpoint
operation:
{http://jbi.exercise.com/xml/ns/services/federalcriminalregister}getCriminalRecords
in: <?xml version="1.0" encoding="UTF-8"?><getCriminalRecords
xmlns="http://jbi.exercise.com/xml/ns/services/federalcriminalregister";
xmlns:ns2="http://jbi.exercise.com/xml/ns/services/federalcriminalregister";>
<ns2:principalId>2.2.2.2</ns2:principalId>
</getCriminalRecords>
out: <?xml version="1.0" encoding="UTF-8"?><getCriminalRecordsResponse
xmlns="http://jbi.exercise.com/xml/ns/services/federalcriminalregister";><criminalRecords><ns1:CriminalRecord
xmlns:ns1="http://federalcriminalregister.services.jbi.exercise.com";><dateOfArrest
xmlns="http://federalcriminalregister.services.jbi.exercise.com";>1990-04-20T00:00:00+02:00</dateOfArrest><dateOfDischarge
xmlns="http://federalcriminalregister.services.jbi.exercise.com";>1991-03-20T00:00:00+01:00</dateOfDischarge><dateOfIncident
xmlns="http://federalcriminalregister.services.jbi.exercise.com";>1990-03-18T00:00:00+01:00</dateOfIncident><kindOfIncident
xmlns="http://federalcriminalregister.services.jbi.exercise.com";>Theft</kindOfIncident></ns1:CriminalRecord></criminalRecords></getCriminalRecordsResponse>
]
15:15:14,450 | DEBUG | Thread-19 | DeliveryChannelImpl |
.messaging.DeliveryChannelImpl 615 | Received: InOut[
id: ID:chav548c-1815-1163599444703-4:25
status: Done
role: provider
service:
{http://jbi.exercise.com/xml/ns/services/federalcriminalregister}FederalCriminalRegisterService
endpoint: JbiEndpoint
operation:
{http://jbi.exercise.com/xml/ns/services/federalcriminalregister}getCriminalRecords
in: <?xml version="1.0" encoding="UTF-8"?><getCriminalRecords
xmlns="http://jbi.exercise.com/xml/ns/services/federalcriminalregister";
xmlns:ns2="http://jbi.exercise.com/xml/ns/services/federalcriminalregister";>
<ns2:principalId>2.2.2.2</ns2:principalId>
</getCriminalRecords>
out: <?xml version="1.0" encoding="UTF-8"?><getCriminalRecordsResponse
xmlns="http://jbi.exercise.com/xml/ns/services/federalcriminalregister";><criminalRecords><ns1:CriminalRecord
xmlns:ns1="http://federalcriminalregister.services.jbi.exercise.com";><dateOfArrest
xmlns="http://federalcriminalregister.services.jbi.exercise.com";>1990-04-20T00:00:00+02:00</dateOfArrest><dateOfDischarge
xmlns="http://federalcriminalregister.services.jbi.exercise.com";>1991-03-20T00:00:00+01:00</dateOfDischarge><dateOfIncident
xmlns="http://federalcriminalregister.services.jbi.exercise.com";>1990-03-18T00:00:00+01:00</dateOfIncident><kindOfIncident
xmlns="http://federalcriminalregister.services.jbi.exercise.com";>Theft</kindOfIncident></ns1:CriminalRecord></criminalRecords></getCriminalRecordsResponse>
]
15:15:14,450 | DEBUG | Thread-19 | Jsr181Component |
emix.common.AsyncBaseLifeCycle 388 | Received exchange: status: Done, role:
provider
15:15:14,450 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 349 | Writing Channel: {CFRAME
InvokeResponseChannel:null#23 refCount=1, msgs=0, objs=1}
15:15:14,450 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 349 | Writing Channel: {CFRAME
ParentScopeChannel:null#16 refCount=0, msgs=0, objs=1}
15:15:14,450 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 349 | Writing Channel: {CFRAME
ParentScopeChannel:null#18 refCount=0, msgs=0, objs=1}
15:15:14,450 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 349 | Writing Channel: {CFRAME
TerminationChannel:null#3 refCount=0, msgs=0, objs=1}
15:15:14,450 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 349 | Writing Channel: {CFRAME
ParentScopeChannel:null#22 refCount=0, msgs=0, objs=1}
15:15:14,450 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 349 | Writing Channel: {CFRAME
TerminationChannel:null#17 refCount=0, msgs=0, objs=1}
15:15:14,450 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 349 | Writing Channel: {CFRAME
ParentScopeChannel:null#2 refCount=0, msgs=0, objs=1}
15:15:14,450 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 349 | Writing Channel: {CFRAME
ParentScopeChannel:null#21 refCount=0, msgs=0, objs=1}
15:15:14,460 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 349 | Writing Channel: {CFRAME
TerminationChannel:null#1 refCount=0, msgs=0, objs=1}
15:15:14,460 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 349 | Writing Channel: {CFRAME
TerminationChannel:null#20 refCount=0, msgs=0, objs=0}
15:15:14,460 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 349 | Writing Channel: {CFRAME
TerminationChannel:null#14 refCount=0, msgs=0, objs=1}
15:15:14,460 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
e.jacob.vpu.ExecutionQueueImpl 349 | Writing Channel: {CFRAME
ParentScopeChannel:null#5 refCount=0, msgs=0, objs=1}
15:15:14,460 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl |
mpl$ExecutionQueueOutputStream 694 | ReplacmentMap:
getReplacement(OPartnerLink#34) = [EMAIL PROTECTED]
15:15:14,460 | DEBUG | pool-2-thread-2 | OdeService |
org.apache.ode.jbi.OdeService 236 | ODE MEX {MyRoleMex#98354 [Client
ID:chav548c-1815-1163599444703-8:25] calling
{http://jbi.exercise.com/xml/ns/services/criminalregister}CriminalRegisterService.getCriminalRecords(...)}
completed ASYNCHRONOUSLY.
15:15:14,460 | DEBUG | pool-2-thread-2 | OdeService |
org.apache.ode.jbi.OdeService 250 | Commiting ODE MEX {MyRoleMex#98354
[Client ID:chav548c-1815-1163599444703-8:25] calling
{http://jbi.exercise.com/xml/ns/services/criminalregister}CriminalRegisterService.getCriminalRecords(...)}
15:15:18,005 | DEBUG | Timer-2 | AutoDeploymentService |
ramework.AutoDeploymentService 568 | Monitoring directory
D:\servicemix-3.0\install for new or modified archives
15:15:18,005 | DEBUG | Timer-2 | AutoDeploymentService |
ramework.AutoDeploymentService 568 | Monitoring directory
D:\servicemix-3.0\deploy for new or modified archives
15:15:28,011 | DEBUG | Timer-2 | AutoDeploymentService |
ramework.AutoDeploymentService 568 | Monitoring directory
D:\servicemix-3.0\install for new or modified archives
15:15:28,011 | DEBUG | Timer-2 | AutoDeploymentService |
ramework.AutoDeploymentService 568 | Monitoring directory
D:\servicemix-3.0\deploy for new or modified archives
15:15:38,067 | DEBUG | Timer-2 | AutoDeploymentService |
ramework.AutoDeploymentService 568 | Monitoring directory
D:\servicemix-3.0\install for new or modified archives
15:15:38,067 | DEBUG | Timer-2 | AutoDeploymentService |
ramework.AutoDeploymentService 568 | Monitoring directory
D:\servicemix-3.0\deploy for new or modified archives
15:15:42,304 | DEBUG | QuartzScheduler_ODEScheduler-ODE_MisfireHandler |
JobStoreJTA | JobStoreSupport$MisfireHandler 2407 |
MisfireHandler: scanning for misfires...
15:15:42,314 | DEBUG | QuartzScheduler_ODEScheduler-ODE_MisfireHandler |
JobStoreJTA | l.jdbcjobstore.JobStoreSupport 745 | Found 0
triggers that missed their scheduled fire-time.
--
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators:
http://issues.apache.org/jira/secure/Administrators.jspa
-
For more information on JIRA, see: http://www.atlassian.com/software/jira
