On Mon, Sep 29, 2008 at 7:27 AM, NGUYEN Cong Kinh < [EMAIL PROTECTED]> wrote:
> Hi Matthieu, > > Thank you for your helps. Here is full log: > So I see the first message triggering the process execution, the invoke that sets the correlation key value to " http://www.tsi.enst.fr/~elloumi/MediasDB/trailers/kill_bill_small.mp4"; and then the pick being activated. But I don't see any message coming in to trigger that pick, so at least for what I have it looks normal. As long as the engine doesn't get that message it won't complete the pick. Btw a pick with a single onMessage is equivalent to a receive. Cheers, Matthieu > > DEBUG - GeronimoLog.debug(66) | Received one-way message for > TestService.{http:/ > /www.tsi.enst.fr}initiate > DEBUG - GeronimoLog.debug(66) | Starting transaction. > DEBUG - GeronimoLog.debug(66) | Routed: svcQname > {http://www.tsi.enst.fr}TestSer > vice --> BpelProcess[{http://www.tsi.enst.fr}Test-1] > DEBUG - GeronimoLog.debug(66) | ODE routed to operation Operation: > name=initiate > > style=ONE_WAY,0 > Input: name=null > Message: name={http://www.tsi.enst.fr}TestRequestMessage > Part: name=payload > elementName={http://www.tsi.enst.fr}TestRequest from service > {http://www.tsi.ens > t.fr}TestService > DEBUG - GeronimoLog.debug(66) | Invoking ODE using MEX > {MyRoleMex#hqejbhcnphr3mp > 5lpfmt4a [Client hqejbhcnphr3mp5lpfmt49] calling > {http://www.tsi.enst.fr}TestSer > vice.initiate(...)} > DEBUG - GeronimoLog.debug(66) | Message content: <?xml version="1.0" > encoding=" > UTF-8"?> > <message><payload><TestRequest xmlns="http://www.tsi.enst.fr"; > xmlns:q0="http://w > ww.tsi.enst.fr" xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/"; > xmlns: > xsd="http://www.w3.org/2001/XMLSchema"; > xmlns:xsi="http://www.w3.org/2001/XMLSche > ma-instance <http://www.w3.org/2001/XMLSchema-instance>"> > <input>hello world</input> > </TestRequest></payload></message> > DEBUG - GeronimoLog.debug(66) | Routed: svcQname > {http://www.tsi.enst.fr}TestSer > vice --> BpelProcess[{http://www.tsi.enst.fr}Test-1] > DEBUG - GeronimoLog.debug(66) | invoke() EPR= null ==> > BpelProcess[{http://www.t > si.enst.fr}Test-1] > DEBUG - GeronimoLog.debug(66) | Commiting ODE MEX > {MyRoleMex#hqejbhcnphr3mp5lpfm > t4a [Client hqejbhcnphr3mp5lpfmt49] calling > {http://www.tsi.enst.fr}TestService. > initiate(...)} > DEBUG - GeronimoLog.debug(66) | Commiting transaction. > DEBUG - GeronimoLog.debug(66) | Releasing mex hqejbhcnphr3mp5lpfmt4a > DEBUG - GeronimoLog.debug(66) | >> > handleWorkEvent(jobData={mexid=hqejbhcnphr3mp > 5lpfmt4a, pid={http://www.tsi.enst.fr}Test-1, type=INVOKE_INTERNAL}) > DEBUG - GeronimoLog.debug(66) | InvokeInternal event for mexid > hqejbhcnphr3mp5lp > fmt4a > DEBUG - GeronimoLog.debug(66) | GET MEX property > org.apache.ode.bpel.myRoleSessi > onId = null > DEBUG - GeronimoLog.debug(66) | GET MEX property > org.apache.ode.bpel.myRoleSessi > onId = null > DEBUG - GeronimoLog.debug(66) | GET MEX property > org.apache.ode.bpel.partnerRole > SessionId = null > DEBUG - GeronimoLog.debug(66) | INPUTMSG: 36.initiate: MSG RCVD keys=[] > mySessio > nId=null partnerSessionId=null > DEBUG - GeronimoLog.debug(66) | INPUTMSG: 36.initiate: routing failed, > CREATING > NEW INSTANCE > DEBUG - GeronimoLog.debug(66) | BpelRuntimeContextImpl created for > instance 201. > INDEXED STATE={} > DEBUG - GeronimoLog.debug(66) | SELECT: PickResponseChannel#9: USING > CORRELATOR > 36.initiate > DEBUG - GeronimoLog.debug(66) | SELECT: PickResponseChannel#9: CHECKING > for NEW > INSTANCE match > DEBUG - GeronimoLog.debug(66) | INPUTMSGMATCH: Changing process instance > state f > rom ready to active > DEBUG - GeronimoLog.debug(66) | SELECT: PickResponseChannel#9: FOUND match > for N > EW instance mexRef={MyRoleMex#hqejbhcnphr3mp5lpfmt4a [Client > hqejbhcnphr3mp5lpfm > t49] calling {http://www.tsi.enst.fr}TestService.initiate(...)} > DEBUG - GeronimoLog.debug(66) | INVOKING PARTNER: > partnerLink={PartnerLinkInstan > ce partnerLinkDecl=OPartnerLink#37,scopeInstanceId=251}, op=changeScale > channel= > InvokeResponseChannel#20) > DEBUG - GeronimoLog.debug(66) | INVOKE PARTNER (SEP): sessionId=null > partnerSess > ionId=null > DEBUG - GeronimoLog.debug(66) | Routed: svcQname {http://paam}VideoService > --> n > ull > DEBUG - GeronimoLog.debug(66) | Invoking a partner operation: changeScale > DEBUG - GeronimoLog.debug(66) | The service to invoke is the external > service {h > ttp://paam}VideoService:Video > DEBUG - GeronimoLog.debug(66) | Resolving endpoint reference <?xml > version="1.0" > encoding="UTF-8"?> > <service-ref > xmlns="http://docs.oasis-open.org/wsbpel/2.0/serviceref";><EndpointR > eference > xmlns="http://www.w3.org/2005/08/addressing";><Metadata><ServiceName xml > ns="http://www.w3.org/2006/05/addressing/wsdl"; EndpointName="Video" > xmlns:servic > ens="http://paam > ">servicens:VideoService</ServiceName></Metadata><Address>http:/ > > /localhost:8080/mobic/services/Video</Address></EndpointReference></service-ref> > > DEBUG - GeronimoLog.debug(66) | GET MEX property > org.apache.ode.bpel.partnerRole > SessionId = null > DEBUG - GeronimoLog.debug(66) | GET MEX property > org.apache.ode.bpel.myRoleSessi > onId = null > DEBUG - GeronimoLog.debug(66) | My-Role EPR not specified, SEP will not be > used. > > DEBUG - GeronimoLog.debug(66) | Axis2 sending message to > http://localhost:8080/m > obic/services/Video <http://localhost:8080/mobic/services/Video> using MEX > {PartnerRoleMex#hqejbhcnphr3mp5lpfmt4d [PID > {http: > //www.tsi.enst.fr}Test-1] calling > [EMAIL PROTECTED] > ngeScale(...)} > DEBUG - GeronimoLog.debug(66) | Message: <?xml version='1.0' > encoding='utf-8'?>< > soapenv:Envelope > xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/";><soap > env:Body><changeScale xmlns="http://paam";> > <stUrl > xmlns="">http://www.tsi.enst.fr/~elloumi/Medi > asDB/trailers/kill_bill_small.mp4<http://www.tsi.enst.fr/%7Eelloumi/MediasDB/trailers/kill_bill_small.mp4> > </stUrl> > <width xmlns="">200</width> > <height xmlns="">200</height> > </changeScale></soapenv:Body></soapenv:Envelope> > DEBUG - GeronimoLog.debug(66) | > org.apache.ode.axis2.SoapExternalService$CachedO > [EMAIL PROTECTED] has been modified > DEBUG - GeronimoLog.debug(66) | Translating IL Properties for Axis2 > DEBUG - GeronimoLog.debug(66) | replyAsync mex=hqejbhcnphr3mp5lpfmt4d > DEBUG - GeronimoLog.debug(66) | Setting execution state on instance 201 > DEBUG - GeronimoLog.debug(66) | Releasing mex hqejbhcnphr3mp5lpfmt4a > DEBUG - GeronimoLog.debug(66) | Sending stateful TO epr in message header > using > session null > DEBUG - GeronimoLog.debug(66) | Sending a message containing wsa endpoints > in he > aders for session passing. > DEBUG - GeronimoLog.debug(66) | <?xml version='1.0' > encoding='utf-8'?><soapenv:E > nvelope > xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/";><soapenv:Heade > r><addr:To > xmlns:addr="http://www.w3.org/2005/08/addressing";>http://localhost:80 > 80/mobic/services/Video</addr:To><addr:Action > xmlns:addr="http://www.w3.org/2005 > /08/addressing <http://www.w3.org/2005/08/addressing> > "></addr:Action><addr:ReplyTo > xmlns:addr="http://www.w3.org/2005/0 > 8/addressing <http://www.w3.org/2005/08/addressing>"><addr:Address> > http://www.w3.org/2005/08/addressing/anonymous</addr > :Address></addr:ReplyTo><addr:MessageID > xmlns:addr="http://www.w3.org/2005/08/ad > > dressing">uuid:hqejbhcnphr3mp5lpfmt4e</addr:MessageID></soapenv:Header><soapenv: > Body><changeScale xmlns="http://paam";> > <stUrl > xmlns="">http://www.tsi.enst.fr/~elloumi/Medi > asDB/trailers/kill_bill_small.mp4<http://www.tsi.enst.fr/%7Eelloumi/MediasDB/trailers/kill_bill_small.mp4> > </stUrl> > <width xmlns="">200</width> > <height xmlns="">200</height> > </changeScale></soapenv:Body></soapenv:Envelope> > FFmpeg version SVN-r12665, Copyright (c) 2000-2008 Fabrice Bellard, et al. > configuration: --enable-gpl --enable-postproc --enable-swscale > --enable-avfilt > er-lavf --enable-pthreads --enable-liba52 --enable-avisynth > --enable-libfaac --e > nable-libfaad --enable-libgsm --enable-memalign-hack --enable-libmp3lame > --enabl > e-libnut --enable-libtheora --enable-libvorbis --enable-libx264 > --enable-libxvid > --cpu=i686 --extra-ldflags=-static > libavutil version: 49.6.0 > libavcodec version: 51.54.0 > libavformat version: 52.13.0 > libavdevice version: 52.0.0 > built on Apr 2 2008 22:35:11, gcc: 4.2.3 > > Seems stream 2 codec frame rate differs from container frame rate: 25.00 > (25/1) > -> 8.00 (120000/15000) > Input #0, mov,mp4,m4a,3gp,3g2,mj2, from > 'C:\19801009_ENST_PAAM\1222698273484_287 > 4.mp4': > Duration: 00:02:28.4, start: 0.000000, bitrate: 314 kb/s > Stream #0.0(und): Data: mp4s / 0x7334706D > Stream #0.1(und): Data: mp4s / 0x7334706D > Stream #0.2(und): Video: mpeg4, yuv420p, 240x128 [PAR 1:1 DAR 15:8], > 8.00 t > b(r) > Stream #0.3(und): Audio: mpeg4aac, 44100 Hz, stereo > Stream #0.4(und): Data: rtp / 0x20707472 > Stream #0.5(und): Data: rtp / 0x20707472 > Output #0, mp4, to 'C:\19801009_ENST_PAAM\1222698274015_4595.mp4': > Stream #0.0(und): Video: mpeg4, yuv420p, 200x200 [PAR 15:8 DAR 15:8], > q=2-31 > , 200 kb/s, 8.00 tb(c) > Stream #0.1(und): Audio: libfaac, 44100 Hz, stereo, 64 kb/s > Stream mapping: > Stream #0.2 -> #0.0 > Stream #0.3 -> #0.1 > [mpeg4 @ 00ABC290]removing common factors from framerate > Press [q] to stop encoding > frame= 1173 fps= 58 q=1.6 Lsize= 4504kB time=146.6 bitrate= 251.7kbits/s > video:3600kB audio:841kB global headers:0kB muxing overhead 1.434929% > DEBUG - GeronimoLog.debug(66) | Service response: > <?xml version='1.0' encoding='utf-8'?><soapenv:Envelope > xmlns:soapenv="http://sc > hemas.xmlsoap.org/soap/envelope/" > xmlns:xsd="http://www.w3.org/2001/XMLSchema"; x > mlns:xsi="http://www.w3.org/2001/XMLSchema-instance > "><soapenv:Body><changeScaleR > esponse > xmlns="http://paam";><changeScaleReturn>http://vesta.enst.fr:8080/result/ > > 1222698274015_4595.mp4</changeScaleReturn></changeScaleResponse></soapenv:Body>< > /soapenv:Envelope> > DEBUG - GeronimoLog.debug(66) | Received response for MEX > {PartnerRoleMex#hqejbh > cnphr3mp5lpfmt4d [PID {http://www.tsi.enst.fr}Test-1] calling > null.changeScale(. > ..)} > INFO - GeronimoLog.info(79) | Response: > <?xml version="1.0" encoding="UTF-8"?> > <message><parameters><changeScaleResponse xmlns="http://paam"; > xmlns:soapenv="htt > p://schemas.xmlsoap.org/soap/envelope/" > xmlns:xsd="http://www.w3.org/2001/XMLSch > ema" > xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance";><changeScaleReturn > xm > lns="http://paam";>http://vesta.enst.fr:8080/result/1222698274015_4595.mp4 > </chang > eScaleReturn></changeScaleResponse></parameters></message> > DEBUG - GeronimoLog.debug(66) | reply mex=hqejbhcnphr3mp5lpfmt4d > DEBUG - GeronimoLog.debug(66) | create work event for > mex=hqejbhcnphr3mp5lpfmt4d > > DEBUG - GeronimoLog.debug(66) | Thread[ODEServer-1,5,main]: lock(iid=201, > time=1 > MICROSECONDS) > DEBUG - GeronimoLog.debug(66) | Thread[ODEServer-1,5,main]: lock(iid=201, > time=1 > MICROSECONDS)-->GRANTED > DEBUG - GeronimoLog.debug(66) | >> > handleWorkEvent(jobData={mexid=hqejbhcnphr3mp > 5lpfmt4d, iid=201, type=INVOKE_RESPONSE, channel=20}) > DEBUG - GeronimoLog.debug(66) | BpelRuntimeContextImpl created for > instance 201. > INDEXED > STATE={OSequence#38-main::2=[SEQUENCE(self=(OSequence#38-main,Terminati > onChannel#3,ParentScopeChannel#5), > linkframe=org.apache.ode.bpel.runtime.LinkFra > [EMAIL PROTECTED], remaining=[{OScope 'Invoke' id=49}, {OScope 'Scope' id=56}, > {OScope > 'callbackClient' id=90}])], {OScope 'Invoke' > id=49}::9=[ACTIVITYGUARD(...)], OIn > voke#50-Invoke::12=[INVOKE(...)], {OScope '__PROCESS_SCOPE:Test' > id=20}::0=[ACTI > VE(...)], {OScope 'Invoke' id=49}::10=[ACTIVE(...)], > OSequence#38-main::1=[ACTIV > ITYGUARD(...)], {OScope '__PROCESS_SCOPE:Test' id=20}::0=[SCOPE(...)], > OInvoke#5 > 0-Invoke::11=[ACTIVITYGUARD(...)], {OScope 'Invoke' > id=49}::10=[SCOPE(...)]} > DEBUG - GeronimoLog.debug(66) | InvokeResponse event for iid 201 > DEBUG - GeronimoLog.debug(66) | Invoking message response for mexid > hqejbhcnphr3 > mp5lpfmt4d and channel 20 > DEBUG - GeronimoLog.debug(66) | Triggering response > DEBUG - GeronimoLog.debug(66) | SELECT: PickResponseChannel#34: USING > CORRELATOR > 36.say > DEBUG - GeronimoLog.debug(66) | SELECT: PickResponseChannel#34: ADDED > ROUTE 36.s > ay: {CorrelationKey setId=35, > values=[http://www.tsi.enst.fr/~elloumi/MediasDB/t<http://www.tsi.enst.fr/%7Eelloumi/MediasDB/t> > railers/kill_bill_small.mp4]} --> 201 > DEBUG - GeronimoLog.debug(66) | Setting execution state on instance 201 > DEBUG - GeronimoLog.debug(66) | Thread[ODEServer-1,5,main]: unlock(iid=201) > DEBUG - GeronimoLog.debug(66) | Thread[ODEServer-1,5,main]: lock(iid=201, > time=1 > MICROSECONDS) > DEBUG - GeronimoLog.debug(66) | Thread[ODEServer-1,5,main]: lock(iid=201, > time=1 > MICROSECONDS)-->GRANTED > DEBUG - GeronimoLog.debug(66) | >> > handleWorkEvent(jobData={ckey=35~http://www.t > si.enst.fr/~~elloumi/MediasDB/trailers/kill_bill_small.mp4<http://si.enst.fr/%7E%7Eelloumi/MediasDB/trailers/kill_bill_small.mp4> > , > correlatorId=36.say, > inmem=false, iid=201, type=MATCHER}) > DEBUG - GeronimoLog.debug(66) | BpelRuntimeContextImpl created for > instance 201. > INDEXED STATE={OSequence#57-Sequence::15=[ACTIVITYGUARD(...)], > OSequence#57-Seq > > uence::16=[SEQUENCE(self=(OSequence#57-Sequence,TerminationChannel#25,ParentScop > eChannel#27), [EMAIL PROTECTED], > remaining > =[OPickReceive#67-Pick])], > OSequence#38-main::2=[SEQUENCE(self=(OSequence#38-mai > n,TerminationChannel#3,ParentScopeChannel#5), > linkframe=org.apache.ode.bpel.runt > [EMAIL PROTECTED], remaining=[{OScope 'Scope' id=56}, {OScope > 'callbackClien > t' id=90}])], {OScope '__PROCESS_SCOPE:Test' id=20}::0=[ACTIVE(...)], > {OScope '_ > _PROCESS_SCOPE:Test' id=20}::0=[SCOPE(...)], {OScope 'Scope' > id=56}::14=[SCOPE(. > ..)], OSequence#38-main::1=[ACTIVITYGUARD(...)], > OPickReceive#67-Pick::20=[PICK( > ...)], {OScope 'Scope' id=56}::14=[ACTIVE(...)], {OScope 'Scope' > id=56}::13=[ACT > IVITYGUARD(...)], OPickReceive#67-Pick::19=[ACTIVITYGUARD(...)]} > DEBUG - GeronimoLog.debug(66) | Matcher event for iid 201 > DEBUG - GeronimoLog.debug(66) | MatcherEvent handling: > correlatorId=36.say, ckey > ={CorrelationKey setId=35, > values=[http://www.tsi.enst.fr/~elloumi/MediasDB/trai > lers/kill_bill_small.mp4<http://www.tsi.enst.fr/%7Eelloumi/MediasDB/trailers/kill_bill_small.mp4> > ]} > DEBUG - GeronimoLog.debug(66) | MatcherEvent handling: nothing to do, no > matchin > g message in DB > DEBUG - GeronimoLog.debug(66) | Thread[ODEServer-1,5,main]: unlock(iid=201) > > -- > Kinh > > >
