ode-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Matthias von Arx (JIRA)" <j...@apache.org>
Subject [jira] Updated: (ODE-80) unreliable delivery of respons messages
Date Wed, 15 Nov 2006 15:00:41 GMT
     [ http://issues.apache.org/jira/browse/ODE-80?page=all ]

Matthias von Arx updated ODE-80:
--------------------------------

    Attachment: sa-criminalregister.zip
                sa-federalcriminalregister.zip

> 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
>         Attachments: ode_criminalregister_client.html, sa-criminalregister.zip, sa-federalcriminalregister.zip
>
>
> 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) = org.apache.ode.bpel.engine.BpelProcess$OBaseReplacementImpl@1d0ff95
> 15:15:14,420 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl       | mpl$ExecutionQueueOutputStream
 694 | ReplacmentMap: getReplacement({OScope '__PROCESS_SCOPE:CriminalRegisterProcess' id=3})
= org.apache.ode.bpel.engine.BpelProcess$OBaseReplacementImpl@d210b2
> 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/}sequence@63)
= org.apache.ode.bpel.engine.BpelProcess$OBaseReplacementImpl@e9c7e4
> 15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl       | mpl$ExecutionQueueOutputStream
 694 | ReplacmentMap: getReplacement({OAssign : assign3, joinCondition=null}) = org.apache.ode.bpel.engine.BpelProcess$OBaseReplacementImpl@1712372
> 15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl       | mpl$ExecutionQueueOutputStream
 694 | ReplacmentMap: getReplacement(OReply#86-end) = org.apache.ode.bpel.engine.BpelProcess$OBaseReplacementImpl@ce2fdb
> 15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl       | mpl$ExecutionQueueOutputStream
 694 | ReplacmentMap: getReplacement({OScope 'invokeFederalRegister' id=65}) = org.apache.ode.bpel.engine.BpelProcess$OBaseReplacementImpl@2ff64
> 15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl       | mpl$ExecutionQueueOutputStream
 694 | ReplacmentMap: getReplacement(OInvoke#66-invokeFederalRegister) = org.apache.ode.bpel.engine.BpelProcess$OBaseReplacementImpl@7e6da
> 15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl       | mpl$ExecutionQueueOutputStream
 694 | ReplacmentMap: getReplacement({Variable __PROCESS_SCOPE:CriminalRegisterProcess.federalResponse:OMessageVarType#22})
= org.apache.ode.bpel.engine.BpelProcess$OBaseReplacementImpl@1f6ad64
> 15:15:14,440 | DEBUG | pool-2-thread-2 | ExecutionQueueImpl       | mpl$ExecutionQueueOutputStream
 694 | ReplacmentMap: getReplacement(OProcess#0) = org.apache.ode.bpel.engine.BpelProcess$OBaseReplacementImpl@1003ba9
> 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 | org.apache.servicemix.jbi.nmr.flow.seda.SedaQueue$1@931229 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) = org.apache.ode.bpel.engine.BpelProcess$OBaseReplacementImpl@1a4c884
> 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

        

Mime
View raw message