Well finally the problem it wasn´t in the datasource, the default ode-jbi
database configuration is OK.
The problem is that OdeService could not find a mapper for the request. The
webservice haven´t IN parameters and looks like OdeService could not find a
mapper to the MessageExange that haven´t parameters.
Well I modify the bpel web service and I added a parameter that I dont use,
but the web service that invokes bpel dont have any parameters. I would want
to check if OdeService could find a mapper to the request with one parameter
and then Bpel invokes the webservice ignoring that parameter.
Well the result is that yes find the mapper and the first problem is solved
but I have another problem, there isn´t any exception but bpel no invkokes
the web-service:
The trace log is:
In the trace "esDiaHabilAhoraBPEL" is the method that receibe the Bpel and
the message contains
a parameter "<ns0:out>false</ns0:out>" is a boolean parameter that is never
used to invoke the web-service.
-------------------------------------------------------------------------------------------------------------
11:38:52,499 | DEBUG | btpool0-2 | HttpComponent |
emix.common.AsyncBaseLifeCycle 538 | Created correlation id:
ID:192.168.1.142-11680da62bb-3:0
11:38:52,500 | DEBUG | btpool0-2 | DeliveryChannelImpl |
.messaging.DeliveryChannelImpl 413 | Send ID:192.168.1.142-11680da62bb-3:0
in DeliveryChannel{servicemix-http}
11:38:52,502 | DEBUG | btpool0-2 | SedaFlow |
emix.jbi.nmr.flow.AbstractFlow 121 | Called Flow send
11:38:52,509 | DEBUG | btpool0-2 | ConsumerProcessor |
p.processors.ConsumerProcessor 185 | Suspending continuation for exchange:
ID:192.168.1.142-11680da62bb-3:0
11:38:52,519 | DEBUG | pool-flow.seda.OdeBpelEngine-thread-1 | SedaQueue
| .jbi.nmr.flow.seda.SedaQueue$1 132 |
[EMAIL PROTECTED] dequeued
exchange: InOut[
id: ID:192.168.1.142-11680da62bb-3:0
status: Active
role: provider
service:
{http://diashabiles.interoperatividad.ignos.com}DiasHabilesServiceImpl
endpoint: endpointDiaHabilBpel
operation:
{http://diashabiles.interoperatividad.ignos.com}esDiaHabilAhoraBPEL
in: <?xml version="1.0" encoding="UTF-8"?><
ns0:esDiaHabilAhoraBPEL
xmlns:ns0="http://diashabiles.interoperatividad.ignos.com";
xmlns:enc="http://schemas.xmlsoap.org/soap/encoding/";
xmlns:env="http://schemas.xmlsoap.org/soap/envelope/";
xmlns:xsd="http://www.w3.org/2001/XMLSchema";
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance";>
<ns0:out>false</ns0:out>
</ns0:esDiaHabilAhoraBPEL>
]
11:38:52,520 | DEBUG | Thread-8 | DeliveryChannelImpl |
.messaging.DeliveryChannelImpl 265 | Accepting
ID:192.168.1.142-11680da62bb-3:0 in DeliveryChannel{OdeBpelEngine}
11:38:52,523 | DEBUG | pool-4-thread-1 | OdeService |
org.apache.ode.jbi.OdeService 225 | invokeOde() JBI
exchangeId=ID:192.168.1.142-11680da62bb-3:0
endpoint={http://diashabiles.interoperatividad.ignos.com}DiasHabilesServiceImpl:endpointDiaHabilBpel
operation={http://diashabiles.interoperatividad.ignos.com}esDiaHabilAhoraBPEL
11:38:52,526 | DEBUG | pool-4-thread-1 | BpelEngineImpl |
ode.bpel.engine.BpelEngineImpl 244 | Routed: svcQname
{http://diashabiles.interoperatividad.ignos.com}DiasHabilesServiceImpl -->
BpelProcess[{http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL-12]
11:38:52,543 | DEBUG | pool-4-thread-1 | ServiceMixMapper |
de.jbi.msgmap.ServiceMixMapper 59 | isRecognized() message: <?xml
version="1.0" encoding="UTF-8"?>
<ns0:esDiaHabilAhoraBPEL
xmlns:enc="http://schemas.xmlsoap.org/soap/encoding/";
xmlns:env="http://schemas.xmlsoap.org/soap/envelope/";
xmlns:ns0="http://diashabiles.interoperatividad.ignos.com";
xmlns:xsd="http://www.w3.org/2001/XMLSchema";
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance";>
<ns0:out>false</ns0:out>
</ns0:esDiaHabilAhoraBPEL>
11:38:52,545 | DEBUG | pool-4-thread-1 | MessageExchangeImpl |
pel.engine.MessageExchangeImpl 220 | SET MEX property
org.apache.ode.jbi.msgmap.Mapper =
org.apache.ode.jbi.msgmap.ServiceMixMapper
11:38:52,546 | DEBUG | pool-4-thread-1 | ServiceMixMapper |
de.jbi.msgmap.ServiceMixMapper 170 | toODE() normalized message:
<?xml version="1.0" encoding="UTF-8"?>
<ns0:esDiaHabilAhoraBPEL
xmlns:enc="http://schemas.xmlsoap.org/soap/encoding/";
xmlns:env="http://schemas.xmlsoap.org/soap/envelope/";
xmlns:ns0="http://diashabiles.interoperatividad.ignos.com";
xmlns:xsd="http://www.w3.org/2001/XMLSchema";
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance";>
<ns0:out>false</ns0:out>
</ns0:esDiaHabilAhoraBPEL>
11:38:52,546 | DEBUG | pool-4-thread-1 | ServiceMixMapper |
de.jbi.msgmap.ServiceMixMapper 184 | toODE() doc-like message
11:38:52,563 | DEBUG | pool-4-thread-1 | BpelEngineImpl |
ode.bpel.engine.BpelEngineImpl 244 | Routed: svcQname
{http://diashabiles.interoperatividad.ignos.com}DiasHabilesServiceImpl -->
BpelProcess[{http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL-12]
11:38:52,564 | DEBUG | pool-4-thread-1 | yRoleMessageExchangeImpl |
gine.MyRoleMessageExchangeImpl 117 | invoke() EPR= null ==>
BpelProcess[{http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL-12]
11:38:52,568 | DEBUG | pool-4-thread-1 | SimpleScheduler |
heduler.simple.SimpleScheduler 210 | scheduling {type=INVOKE_INTERNAL,
mexid=hqejbhcnphr2s3f410mg3e,
pid={http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL-12}
for Tue Nov 27 11:38:52 UTC 2007
11:38:52,569 | DEBUG | pool-4-thread-1 | JdbcDelegate |
.scheduler.simple.JdbcDelegate 127 | insertJob hqejbhcnphr2s3f410mg3f on
node hqejbhcnphr2s3f410mg3c loaded=true
11:38:52,570 | DEBUG | pool-4-thread-1 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:52,570 | DEBUG | pool-4-thread-1 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: insert into ODE_JOB
(jobid, nodeid, ts, scheduled, transacted, details) values(?, ?, ?, ?, ?, ?)
11:38:52,582 | DEBUG | pool-4-thread-1 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,hqejbhcnphr2s3f410mg3c) (4,1)
(6,[EMAIL PROTECTED]) (1,hqejbhcnphr2s3f410mg3f) (3,1196163532567) (5,1)
11:38:52,585 | DEBUG | pool-4-thread-1 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:52,587 | DEBUG | pool-4-thread-1 | SimpleScheduler |
heduler.simple.SimpleScheduler 228 | scheduled immediate job:
hqejbhcnphr2s3f410mg3f
11:38:52,589 | DEBUG | pool-4-thread-1 | OdeService |
org.apache.ode.jbi.OdeService 253 | ODE MEX
{MyRoleMex#hqejbhcnphr2s3f410mg3e [Client ID:192.168.1.142-11680da62bb-3:0]
calling
{http://diashabiles.interoperatividad.ignos.com}DiasHabilesServiceImpl.esDiaHabilAhoraBPEL(...)}
completed ASYNCHRONOUSLY.
11:38:52,589 | DEBUG | pool-4-thread-1 | OdeService |
org.apache.ode.jbi.OdeService 269 | Commiting ODE MEX
{MyRoleMex#hqejbhcnphr2s3f410mg3e [Client ID:192.168.1.142-11680da62bb-3:0]
calling
{http://diashabiles.interoperatividad.ignos.com}DiasHabilesServiceImpl.esDiaHabilAhoraBPEL(...)}
11:38:52,596 | DEBUG | pool-4-thread-1 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:52,601 | DEBUG | pool-4-thread-1 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT SEQUENCE_VALUE
FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? FOR UPDATE WITH RR
11:38:52,622 | DEBUG | pool-4-thread-1 | sql |
.utils.LoggingStatementWrapper 756 | bound (1,0)
11:38:52,624 | DEBUG | pool-4-thread-1 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: UPDATE
OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND
SEQUENCE_VALUE = ?
11:38:52,638 | DEBUG | pool-4-thread-1 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,0) (1,1101) (3,1051)
11:38:52,639 | DEBUG | OdeScheduler | SimpleScheduler |
heduler.simple.SimpleScheduler 400 | LOAD IMMEDIATE started
11:38:52,641 | DEBUG | OdeScheduler | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:52,641 | DEBUG | OdeScheduler | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: select jobid, ts,
transacted, scheduled, details from ODE_JOB where nodeid = ? and scheduled =
0 and ts < ? order by ts
11:38:52,642 | DEBUG | OdeScheduler | sql |
.utils.LoggingStatementWrapper 756 | bound (2,1196163592640)
(1,hqejbhcnphr2s3f410mg3c)
11:38:52,662 | DEBUG | pool-4-thread-1 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:52,678 | DEBUG | pool-4-thread-1 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:52,691 | DEBUG | pool-4-thread-1 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO
ODE_MESSAGE_EXCHANGE (MESSAGE_EXCHANGE_ID, CALLEE, CHANNEL, CORRELATION_ID,
CORRELATION_KEYS, CORRELATION_STATUS, CREATE_TIME, DIRECTION, EPR, FAULT,
FAULT_EXPLANATION, OPERATION, PARTNER_LINK_MODEL_ID, PATTERN, PIPED_ID,
PORT_TYPE, PROPAGATE_TRANS, STATUS, CORR_ID, PARTNER_LINK_ID, PROCESS_ID,
PROCESS_INSTANCE_ID, REQUEST_MESSAGE_ID, RESPONSE_MESSAGE_ID) VALUES (?, ?,
?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
11:38:52,724 | DEBUG | pool-4-thread-1 | sql |
.utils.LoggingStatementWrapper 756 | bound (15,null)
(4,ID:192.168.1.142-11680da62bb-3:0) (19,null) (8,77) (23,1052) (11,null)
(16,null) (18,ASYNC) (3,null) (7,null) (12,esDiaHabilAhoraBPEL) (22,null)
(17,0)
(2,{http://diashabiles.interoperatividad.ignos.com}DiasHabilesServiceImpl)
(13,0) (9,null) (21,null) (6,UKNOWN_ENDPOINT) (1,hqejbhcnphr2s3f410mg3e)
(20,null) (14,UNKNOWN) (24,null) (10,null) (5,null)
11:38:52,729 | DEBUG | pool-4-thread-1 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_MEX_PROP
(ID, PROP_KEY, PROP_VALUE, MEX_ID) VALUES (?, ?, ?, ?)
11:38:52,795 | DEBUG | pool-4-thread-1 | sql |
.utils.LoggingStatementWrapper 756 | bound
(2,org.apache.ode.jbi.msgmap.Mapper) (4,hqejbhcnphr2s3f410mg3e) (1,1051)
(3,org.apache.ode.jbi.msgmap.ServiceMixMapper)
11:38:52,798 | DEBUG | pool-4-thread-1 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_MESSAGE
(MESSAGE_ID, DATA, TYPE, MESSAGE_EXCHANGE_ID) VALUES (?, ?, ?, ?)
11:38:52,814 | DEBUG | pool-4-thread-1 | sql |
.utils.LoggingStatementWrapper 756 | bound (4,hqejbhcnphr2s3f410mg3e)
(1,1052)
(3,{http://diashabiles.interoperatividad.ignos.com}esDiaHabilAhoraRequest)
11:38:52,833 | DEBUG | OdeScheduler | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: update ODE_JOB set
scheduled = 1 where jobid in (?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
11:38:52,834 | DEBUG | OdeScheduler | sql |
.utils.LoggingStatementWrapper 756 | bound (2,) (4,) (9,) (8,) (6,) (1,)
(3,) (10,) (7,) (5,)
11:38:52,835 | DEBUG | OdeScheduler | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:52,835 | DEBUG | OdeScheduler | SimpleScheduler |
heduler.simple.SimpleScheduler 421 | LOAD IMMEDIATE complete
11:38:52,836 | DEBUG | pool-4-thread-1 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:52,845 | DEBUG | pool-2-thread-2 | BpelProcess |
he.ode.bpel.engine.BpelProcess 294 | >>
handleWorkEvent(jobData={type=INVOKE_INTERNAL, mexid=hqejbhcnphr2s3f410mg3e,
pid={http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL-12})
11:38:52,846 | DEBUG | pool-2-thread-2 | BpelProcess |
he.ode.bpel.engine.BpelProcess 303 | InvokeInternal event for mexid
hqejbhcnphr2s3f410mg3e
11:38:52,853 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:52,853 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT t0.CALLEE,
t0.CHANNEL, t0.CORRELATION_ID, t0.CORRELATION_KEYS, t0.CORRELATION_STATUS,
t0.CORR_ID, t0.CREATE_TIME, t0.DIRECTION, t0.EPR, t0.FAULT,
t0.FAULT_EXPLANATION, t0.OPERATION, t0.PARTNER_LINK_ID,
t0.PARTNER_LINK_MODEL_ID, t0.PATTERN, t0.PIPED_ID, t0.PORT_TYPE,
t0.PROCESS_ID, t0.PROCESS_INSTANCE_ID, t0.PROPAGATE_TRANS,
t0.REQUEST_MESSAGE_ID, t0.RESPONSE_MESSAGE_ID, t0.STATUS, t1.MEX_ID, t1.ID,
t1.PROP_KEY, t1.PROP_VALUE FROM ODE_MESSAGE_EXCHANGE t0 LEFT OUTER JOIN
ODE_MEX_PROP t1 ON t0.MESSAGE_EXCHANGE_ID = t1.MEX_ID WHERE
t0.MESSAGE_EXCHANGE_ID = ? ORDER BY t1.MEX_ID ASC
11:38:52,888 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (1,hqejbhcnphr2s3f410mg3e)
11:38:52,895 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:52,897 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:52,898 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT t0.ID, t0.GUID,
t0.NUMBER_OF_INSTANCES, t0.PROCESS_ID, t0.PROCESS_TYPE, t0.VERSION FROM
ODE_PROCESS t0 WHERE (t0.PROCESS_ID = ?)
11:38:52,899 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound
(1,{http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL-12)
11:38:52,920 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:52,922 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:52,923 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT t0.ID, t0.GUID,
t0.NUMBER_OF_INSTANCES, t0.PROCESS_ID, t0.PROCESS_TYPE, t0.VERSION FROM
ODE_PROCESS t0 WHERE (t0.PROCESS_ID = ?)
11:38:52,924 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound
(1,{http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL-12)
11:38:52,926 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:52,928 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:52,929 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT t0.ID, t0.GUID,
t0.NUMBER_OF_INSTANCES, t0.PROCESS_ID, t0.PROCESS_TYPE, t0.VERSION FROM
ODE_PROCESS t0 WHERE (t0.PROCESS_ID = ?)
11:38:52,930 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound
(1,{http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL-12)
11:38:52,932 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:52,944 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:52,945 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT t0.CORRELATOR_ID,
t0.CORRELATOR_KEY, t0.PROC_ID FROM ODE_CORRELATOR t0 WHERE
(t0.CORRELATOR_KEY = ? AND t0.PROC_ID = ?)
11:38:52,990 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,1001)
(1,36.esDiaHabilAhoraBPEL)
11:38:52,992 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT t0.CORRELATOR_ID,
t1.MESSAGE_ROUTE_ID, t1.CORRELATION_KEY, t1.CORR_ID, t1.GROUP_ID,
t1.ROUTE_INDEX, t1.PROCESS_INSTANCE_ID FROM ODE_CORRELATOR t0 INNER JOIN
ODE_MESSAGE_ROUTE t1 ON t0.CORRELATOR_ID = t1.CORR_ID WHERE
(t0.CORRELATOR_KEY = ? AND t0.PROC_ID = ?) ORDER BY t0.CORRELATOR_ID ASC
11:38:53,023 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,1001)
(1,36.esDiaHabilAhoraBPEL)
11:38:53,028 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:53,030 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:53,030 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT t0.DATA,
t0.MESSAGE_EXCHANGE_ID, t0.TYPE FROM ODE_MESSAGE t0 WHERE t0.MESSAGE_ID = ?
11:38:53,041 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (1,1052)
11:38:53,058 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:53,062 | DEBUG | pool-2-thread-2 | MessageExchangeImpl |
pel.engine.MessageExchangeImpl 213 | GET MEX property
org.apache.ode.bpel.myRoleSessionId = null
11:38:53,062 | DEBUG | pool-2-thread-2 | MessageExchangeImpl |
pel.engine.MessageExchangeImpl 213 | GET MEX property
org.apache.ode.bpel.myRoleSessionId = null
11:38:53,063 | DEBUG | pool-2-thread-2 | MessageExchangeImpl |
pel.engine.MessageExchangeImpl 213 | GET MEX property
org.apache.ode.bpel.partnerRoleSessionId = null
11:38:53,064 | DEBUG | pool-2-thread-2 | BpelProcess |
l.engine.PartnerLinkMyRoleImpl 138 | INPUTMSG: 36.esDiaHabilAhoraBPEL: MSG
RCVD keys=[] mySessionId=null partnerSessionId=null
11:38:53,064 | DEBUG | pool-2-thread-2 | BpelProcess |
l.engine.PartnerLinkMyRoleImpl 164 | INPUTMSG: 36.esDiaHabilAhoraBPEL:
routing failed, CREATING NEW INSTANCE
11:38:53,066 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:53,066 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT t0.ID, t0.GUID,
t0.NUMBER_OF_INSTANCES, t0.PROCESS_ID, t0.PROCESS_TYPE, t0.VERSION FROM
ODE_PROCESS t0 WHERE (t0.PROCESS_ID = ?)
11:38:53,067 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound
(1,{http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL-12)
11:38:53,069 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:53,071 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:53,072 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT t0.ID, t0.GUID,
t0.NUMBER_OF_INSTANCES, t0.PROCESS_ID, t0.PROCESS_TYPE, t0.VERSION FROM
ODE_PROCESS t0 WHERE (t0.PROCESS_ID = ?)
11:38:53,073 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound
(1,{http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL-12)
11:38:53,094 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:53,134 | DEBUG | pool-2-thread-2 | JacobVPU |
.apache.ode.jacob.vpu.JacobVPU 215 | injecting PROCESS(...)
11:38:53,136 | DEBUG | pool-2-thread-2 | BpelRuntimeContextImpl |
.engine.BpelRuntimeContextImpl 147 | BpelRuntimeContextImpl created for
instance 1053. INDEXED STATE={}
11:38:53,137 | INFO | pool-2-thread-2 | Query |
log.Log4JLogFactory$LogAdapter 80 | This query on type "class
org.apache.ode.dao.jpa.ProcessDAOImpl" must load the entire candidate class
extent and evaluate the query in-memory. This may be very slow. The query
must be executed in memory because OpenJPA is configured with
IgnoreCache=false and FlushBeforeQueries=false and there are dirty instances
that may affect the query's outcome in the cache.
11:38:53,161 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:53,162 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT t0.ID, t0.GUID,
t0.NUMBER_OF_INSTANCES, t0.PROCESS_ID, t0.PROCESS_TYPE, t0.VERSION FROM
ODE_PROCESS t0
11:38:53,176 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:53,260 | INFO | pool-2-thread-2 | Query |
log.Log4JLogFactory$LogAdapter 80 | This query on type "class
org.apache.ode.dao.jpa.ScopeDAOImpl" must load the entire candidate class
extent and evaluate the query in-memory. This may be very slow. The query
must be executed in memory because OpenJPA is configured with
IgnoreCache=false and FlushBeforeQueries=false and there are dirty instances
that may affect the query's outcome in the cache.
11:38:53,267 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:53,268 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT t0.SCOPE_ID,
t0.MODEL_ID, t0.SCOPE_NAME, t0.PARENT_SCOPE_ID, t0.PROCESS_INSTANCE_ID,
t0.SCOPE_STATE FROM ODE_SCOPE t0
11:38:53,312 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:53,338 | INFO | pool-2-thread-2 | Query |
log.Log4JLogFactory$LogAdapter 80 | This query on type "class
org.apache.ode.dao.jpa.ScopeDAOImpl" must load the entire candidate class
extent and evaluate the query in-memory. This may be very slow. The query
must be executed in memory because OpenJPA is configured with
IgnoreCache=false and FlushBeforeQueries=false and there are dirty instances
that may affect the query's outcome in the cache.
11:38:53,339 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:53,340 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT t0.SCOPE_ID,
t0.MODEL_ID, t0.SCOPE_NAME, t0.PARENT_SCOPE_ID, t0.PROCESS_INSTANCE_ID,
t0.SCOPE_STATE FROM ODE_SCOPE t0
11:38:53,347 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:53,349 | INFO | pool-2-thread-2 | Query |
log.Log4JLogFactory$LogAdapter 80 | This query on type "class
org.apache.ode.dao.jpa.ScopeDAOImpl" must load the entire candidate class
extent and evaluate the query in-memory. This may be very slow. The query
must be executed in memory because OpenJPA is configured with
IgnoreCache=false and FlushBeforeQueries=false and there are dirty instances
that may affect the query's outcome in the cache.
11:38:53,350 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:53,354 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT t0.SCOPE_ID,
t0.MODEL_ID, t0.SCOPE_NAME, t0.PARENT_SCOPE_ID, t0.PROCESS_INSTANCE_ID,
t0.SCOPE_STATE FROM ODE_SCOPE t0
11:38:53,360 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:53,390 | DEBUG | pool-2-thread-2 | BpelProcess |
.engine.BpelRuntimeContextImpl 295 | SELECT: PickResponseChannel#12: USING
CORRELATOR 36.esDiaHabilAhoraBPEL
11:38:53,391 | INFO | pool-2-thread-2 | Query |
log.Log4JLogFactory$LogAdapter 80 | This query on type "class
org.apache.ode.dao.jpa.CorrelatorDAOImpl" must load the entire candidate
class extent and evaluate the query in-memory. This may be very slow. The
query must be executed in memory because OpenJPA is configured with
IgnoreCache=false and FlushBeforeQueries=false and there are dirty instances
that may affect the query's outcome in the cache.
11:38:53,397 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:53,398 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT t0.CORRELATOR_ID,
t0.CORRELATOR_KEY, t0.PROC_ID FROM ODE_CORRELATOR t0
11:38:53,407 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT t0.CORRELATOR_ID,
t1.MESSAGE_ROUTE_ID, t1.CORRELATION_KEY, t1.CORR_ID, t1.GROUP_ID,
t1.ROUTE_INDEX, t1.PROCESS_INSTANCE_ID FROM ODE_CORRELATOR t0 INNER JOIN
ODE_MESSAGE_ROUTE t1 ON t0.CORRELATOR_ID = t1.CORR_ID ORDER BY
t0.CORRELATOR_ID ASC
11:38:53,457 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:53,460 | DEBUG | pool-2-thread-2 | BpelProcess |
.engine.BpelRuntimeContextImpl 312 | SELECT: PickResponseChannel#12:
CHECKING for NEW INSTANCE match
11:38:53,461 | DEBUG | pool-2-thread-2 | BpelProcess |
.engine.BpelRuntimeContextImpl 883 | INPUTMSGMATCH: Changing process
instance state from ready to active
11:38:53,463 | DEBUG | pool-2-thread-2 | JacobVPU |
.apache.ode.jacob.vpu.JacobVPU 215 | injecting (...)
11:38:53,464 | DEBUG | pool-2-thread-2 | BpelProcess |
.engine.BpelRuntimeContextImpl 319 | SELECT: PickResponseChannel#12: FOUND
match for NEW instance mexRef={MyRoleMex#hqejbhcnphr2s3f410mg3e [Client
ID:192.168.1.142-11680da62bb-3:0] calling
{http://diashabiles.interoperatividad.ignos.com}DiasHabilesServiceImpl.esDiaHabilAhoraBPEL(...)}
11:38:53,473 | INFO | pool-2-thread-2 | Query |
log.Log4JLogFactory$LogAdapter 80 | This query on type "class
org.apache.ode.dao.jpa.ScopeDAOImpl" must load the entire candidate class
extent and evaluate the query in-memory. This may be very slow. The query
must be executed in memory because OpenJPA is configured with
IgnoreCache=false and FlushBeforeQueries=false and there are dirty instances
that may affect the query's outcome in the cache.
11:38:53,475 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:53,475 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: SELECT t0.SCOPE_ID,
t0.MODEL_ID, t0.SCOPE_NAME, t0.PARENT_SCOPE_ID, t0.PROCESS_INSTANCE_ID,
t0.SCOPE_STATE FROM ODE_SCOPE t0
11:38:53,511 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:53,518 | DEBUG | pool-2-thread-2 | EMPTY |
.apache.ode.bpel.runtime.EMPTY 38 | <empty name=OEmpty#42>
11:38:53,536 | DEBUG | pool-2-thread-2 | BpelProcess |
.engine.BpelRuntimeContextImpl 213 | ProcessImpl
{http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL-12
completed OK.
11:38:53,546 | DEBUG | pool-2-thread-2 | JdbcDelegate |
.scheduler.simple.JdbcDelegate 83 | deleteJob hqejbhcnphr2s3f410mg3f on
node hqejbhcnphr2s3f410mg3c
11:38:53,547 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:53,547 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: delete from ODE_JOB where
jobid = ? and nodeid = ?
11:38:53,577 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,hqejbhcnphr2s3f410mg3c)
(1,hqejbhcnphr2s3f410mg3f)
11:38:53,584 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:53,588 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingDataSourceWrapper 77 | getConnection (tx=2)
11:38:53,595 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: UPDATE
ODE_MESSAGE_EXCHANGE SET CORRELATION_STATUS = ?, PARTNER_LINK_MODEL_ID = ?,
PATTERN = ?, STATUS = ?, PROCESS_ID = ?, PROCESS_INSTANCE_ID = ? WHERE
MESSAGE_EXCHANGE_ID = ?
11:38:53,613 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,36) (4,COMPLETED_OK) (6,1053)
(1,CREATE_INSTANCE) (3,REQUEST_RESPONSE) (7,hqejbhcnphr2s3f410mg3e) (5,1001)
11:38:53,617 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO
ODE_PROCESS_INSTANCE (ID, DATE_CREATED, EXECUTION_STATE, LAST_ACTIVE_TIME,
LAST_RECOVERY_DATE, PREVIOUS_STATE, SEQUENCE, INSTANCE_STATE, FAULT_ID,
INSTANTIATING_CORRELATOR_ID, PROCESS_ID, ROOT_SCOPE_ID) VALUES (?, ?, ?, ?,
?, ?, ?, ?, ?, ?, ?, ?)
11:38:53,983 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,2007-11-27 11:38:53.095)
(4,2007-11-27 11:38:53.539) (9,null) (8,30) (11,1001) (6,20) (1,1053)
(3,null) (10,1003) (7,9) (12,1054) (5,null)
11:38:53,991 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: UPDATE ODE_PROCESS SET
NUMBER_OF_INSTANCES = ? WHERE ID = ?
11:38:54,048 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,1001) (1,2)
11:38:54,050 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,153 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
NewProcessInstanceEvent:
Type = instanceLifecycle
ScopeDeclarationId = 0
Operation = esDiaHabilAhoraBPEL
PortType =
{http://diashabiles.interoperatividad.ignos.com}DiasHabilesService
MessageExchangeId = hqejbhcnphr2s3f410mg3e
Aspect = 0
ProcessIn) (4,null) (8,1001) (6,NewProcessInstanceEvent) (1,1056)
(3,[EMAIL PROTECTED]) (7,1053) (5,2007-11-27 11:38:53.185)
11:38:54,160 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_SCOPE
(SCOPE_ID, MODEL_ID, SCOPE_NAME, SCOPE_STATE, PROCESS_INSTANCE_ID,
PARENT_SCOPE_ID) VALUES (?, ?, ?, ?, ?, ?)
11:38:54,313 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,3) (4,ACTIVE) (6,null)
(1,1054) (3,__PROCESS_SCOPE:procesoPruebaBPEL) (5,1053)
11:38:54,316 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,320 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ProcessInstanceStartedEvent:
RootScopeId = 1054
ScopeDeclarationId = 3
Type = instanceLifecycle
ProcessInstanceId = 1053
ProcessId =
{http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL-12
ProcessName = {http://diashabiles.intero) (4,null) (8,1001)
(6,ProcessInstanceStartedEvent) (1,1057) (3,[EMAIL PROTECTED]) (7,1053)
(5,2007-11-27 11:38:53.212)
11:38:54,323 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,442 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ActivityEnabledEvent:
Type = activityLifecycle
ActivityId = 1
ActivityName = Main
ActivityType = OSequence
ActivityDeclarationId = 38
ScopeId = 1054
ParentScopesNames = [__PROCESS_SCOPE:procesoPruebaBPEL]
ScopeDeclarationId = 3
ScopeName = __PR) (4,1054) (8,1001) (6,ActivityEnabledEvent) (1,1058)
(3,[EMAIL PROTECTED]) (7,1053) (5,2007-11-27 11:38:53.322)
11:38:54,445 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO
ODE_PARTNER_LINK (PARTNER_LINK_ID, MY_EPR, MY_ROLE_NAME,
MY_ROLE_SERVICE_NAME, MY_SESSION_ID, PARTNER_EPR, PARTNER_LINK_MODEL_ID,
PARTNER_LINK_NAME, PARTNER_ROLE_NAME, PARTNER_SESSION_ID, SCOPE_ID) VALUES
(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,461 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,null) (4,null) (9,null)
(8,DiasHabilesPartnerLink) (11,1054) (6,null) (1,1059) (3,Provider)
(10,null) (7,36) (5,hqejbhcnphr2s3f410mg3g)
11:38:54,474 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO
ODE_PARTNER_LINK (PARTNER_LINK_ID, MY_EPR, MY_ROLE_NAME,
MY_ROLE_SERVICE_NAME, MY_SESSION_ID, PARTNER_EPR, PARTNER_LINK_MODEL_ID,
PARTNER_LINK_NAME, PARTNER_ROLE_NAME, PARTNER_SESSION_ID, SCOPE_ID) VALUES
(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,475 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,null) (4,null) (9,Provider)
(8,DiasHabilesWsPartnerLink) (11,1054) (6,null) (1,1060) (3,null) (10,null)
(7,37) (5,null)
11:38:54,477 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,481 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ActivityExecStartEvent:
Type = activityLifecycle
ActivityId = 1
ActivityName = Main
ActivityType = OSequence
ActivityDeclarationId = 38
ScopeId = 1054
ParentScopesNames = [__PROCESS_SCOPE:procesoPruebaBPEL]
ScopeDeclarationId = 3
ScopeName = __) (4,1054) (8,1001) (6,ActivityExecStartEvent) (1,1061)
(3,[EMAIL PROTECTED]) (7,1053) (5,2007-11-27 11:38:53.323)
11:38:54,483 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,485 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ActivityEnabledEvent:
Type = activityLifecycle
ActivityId = 3
ActivityType = OScope
ActivityDeclarationId = 39
ScopeId = 1054
ParentScopesNames = [__PROCESS_SCOPE:procesoPruebaBPEL]
ScopeDeclarationId = 3
ScopeName = __PROCESS_SCOPE:procesoPrueb) (4,1054) (8,1001)
(6,ActivityEnabledEvent) (1,1062) (3,[EMAIL PROTECTED]) (7,1053) (5,2007-11-27
11:38:53.335)
11:38:54,488 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,489 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ActivityExecStartEvent:
Type = activityLifecycle
ActivityId = 3
ActivityType = OScope
ActivityDeclarationId = 39
ScopeId = 1054
ParentScopesNames = [__PROCESS_SCOPE:procesoPruebaBPEL]
ScopeDeclarationId = 3
ScopeName = __PROCESS_SCOPE:procesoPru) (4,1054) (8,1001)
(6,ActivityExecStartEvent) (1,1063) (3,[EMAIL PROTECTED]) (7,1053) (5,2007-11-27
11:38:53.336)
11:38:54,492 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_SCOPE
(SCOPE_ID, MODEL_ID, SCOPE_NAME, SCOPE_STATE, PROCESS_INSTANCE_ID,
PARENT_SCOPE_ID) VALUES (?, ?, ?, ?, ?, ?)
11:38:54,493 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,39) (4,ACTIVE) (6,1054)
(1,1055) (3,scope1) (5,1053)
11:38:54,495 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,496 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ActivityEnabledEvent:
Type = activityLifecycle
ActivityId = 5
ActivityName = diaHabilReceive
ActivityType = OPickReceive
ActivityDeclarationId = 40
ScopeId = 1055
ParentScopesNames = [scope1, __PROCESS_SCOPE:procesoPruebaBPEL]
ScopeDeclarationId) (4,1055) (8,1001) (6,ActivityEnabledEvent) (1,1064)
(3,[EMAIL PROTECTED]) (7,1053) (5,2007-11-27 11:38:53.377)
11:38:54,499 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,502 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ActivityExecStartEvent:
Type = activityLifecycle
ActivityId = 5
ActivityName = diaHabilReceive
ActivityType = OPickReceive
ActivityDeclarationId = 40
ScopeId = 1055
ParentScopesNames = [scope1, __PROCESS_SCOPE:procesoPruebaBPEL]
ScopeDeclaration) (4,1055) (8,1001) (6,ActivityExecStartEvent) (1,1065)
(3,[EMAIL PROTECTED]) (7,1053) (5,2007-11-27 11:38:53.378)
11:38:54,505 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,509 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ProcessInstanceStateChangeEvent:
OldState = 0
NewState = 10
Type = instanceLifecycle
ProcessInstanceId = 1053
ProcessId =
{http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL-12
ProcessName = {http://diashabiles.interoperatividad) (4,null) (8,1001)
(6,ProcessInstanceStateChangeEvent) (1,1066) (3,[EMAIL PROTECTED]) (7,1053)
(5,2007-11-27 11:38:53.389)
11:38:54,511 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,513 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ProcessInstanceStateChangeEvent:
OldState = 10
NewState = 20
Type = instanceLifecycle
ProcessInstanceId = 1053
ProcessId =
{http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL-12
ProcessName = {http://diashabiles.interoperativida) (4,null) (8,1001)
(6,ProcessInstanceStateChangeEvent) (1,1067) (3,[EMAIL PROTECTED]) (7,1053)
(5,2007-11-27 11:38:53.462)
11:38:54,516 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,520 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
VariableModificationEvent:
Type = dataHandling
VarName = esDiaHabilAhoraRequest
ScopeId = 1055
ParentScopesNames = [scope1, __PROCESS_SCOPE:procesoPruebaBPEL]
ScopeDeclarationId = 39
ScopeName = scope1
ParentScopeId = 1054
ProcessInstanceId = 10) (4,1055) (8,1001) (6,VariableModificationEvent)
(1,1068) (3,[EMAIL PROTECTED]) (7,1053) (5,2007-11-27 11:38:53.513)
11:38:54,523 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_XML_DATA
(XML_DATA_ID, DATA, IS_SIMPLE_TYPE, NAME, SCOPE_ID) VALUES (?, ?, ?, ?, ?)
11:38:54,557 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (4,esDiaHabilAhoraRequest)
(1,1069) (3,0) (5,1054)
11:38:54,563 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,565 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ActivityEnabledEvent:
Type = activityLifecycle
ActivityId = 7
ActivityType = OEmpty
ActivityDeclarationId = 42
ScopeId = 1055
ParentScopesNames = [scope1, __PROCESS_SCOPE:procesoPruebaBPEL]
ScopeDeclarationId = 39
ScopeName = scope1
ParentScope) (4,1055) (8,1001) (6,ActivityEnabledEvent) (1,1070)
(3,[EMAIL PROTECTED]) (7,1053) (5,2007-11-27 11:38:53.515)
11:38:54,568 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,570 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ActivityExecStartEvent:
Type = activityLifecycle
ActivityId = 7
ActivityType = OEmpty
ActivityDeclarationId = 42
ScopeId = 1055
ParentScopesNames = [scope1, __PROCESS_SCOPE:procesoPruebaBPEL]
ScopeDeclarationId = 39
ScopeName = scope1
ParentSco) (4,1055) (8,1001) (6,ActivityExecStartEvent) (1,1071)
(3,[EMAIL PROTECTED]) (7,1053) (5,2007-11-27 11:38:53.516)
11:38:54,572 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,575 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ActivityExecEndEvent:
Type = activityLifecycle
ActivityId = 7
ActivityType = OEmpty
ActivityDeclarationId = 42
ScopeId = 1055
ParentScopesNames = [scope1, __PROCESS_SCOPE:procesoPruebaBPEL]
ScopeDeclarationId = 39
ScopeName = scope1
ParentScope) (4,1055) (8,1001) (6,ActivityExecEndEvent) (1,1072)
(3,[EMAIL PROTECTED]) (7,1053) (5,2007-11-27 11:38:53.519)
11:38:54,599 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,601 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ActivityExecEndEvent:
Type = activityLifecycle
ActivityId = 5
ActivityName = diaHabilReceive
ActivityType = OPickReceive
ActivityDeclarationId = 40
ScopeId = 1055
ParentScopesNames = [scope1, __PROCESS_SCOPE:procesoPruebaBPEL]
ScopeDeclarationId) (4,1055) (8,1001) (6,ActivityExecEndEvent) (1,1073)
(3,[EMAIL PROTECTED]) (7,1053) (5,2007-11-27 11:38:53.521)
11:38:54,603 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,607 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ActivityExecEndEvent:
Type = activityLifecycle
ActivityId = 3
ActivityType = OScope
ActivityDeclarationId = 39
ScopeId = 1054
ParentScopesNames = [__PROCESS_SCOPE:procesoPruebaBPEL]
ScopeDeclarationId = 3
ScopeName = __PROCESS_SCOPE:procesoPrueb) (4,1054) (8,1001)
(6,ActivityExecEndEvent) (1,1074) (3,[EMAIL PROTECTED]) (7,1053) (5,2007-11-27
11:38:53.529)
11:38:54,609 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,611 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ActivityExecEndEvent:
Type = activityLifecycle
ActivityId = 1
ActivityName = Main
ActivityType = OSequence
ActivityDeclarationId = 38
ScopeId = 1054
ParentScopesNames = [__PROCESS_SCOPE:procesoPruebaBPEL]
ScopeDeclarationId = 3
ScopeName = __PR) (4,1054) (8,1001) (6,ActivityExecEndEvent) (1,1075)
(3,[EMAIL PROTECTED]) (7,1053) (5,2007-11-27 11:38:53.535)
11:38:54,613 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,615 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ProcessInstanceStateChangeEvent:
OldState = 20
NewState = 30
Type = instanceLifecycle
ProcessInstanceId = 1053
ProcessId =
{http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL-12
ProcessName = {http://diashabiles.interoperativida) (4,null) (8,1001)
(6,ProcessInstanceStateChangeEvent) (1,1076) (3,[EMAIL PROTECTED]) (7,1053)
(5,2007-11-27 11:38:53.537)
11:38:54,617 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | prepareStmt: INSERT INTO ODE_EVENT
(EVENT_ID, DETAIL, DATA, SCOPE_ID, TSTAMP, TYPE, INSTANCE_ID, PROCESS_ID)
VALUES (?, ?, ?, ?, ?, ?, ?, ?)
11:38:54,620 | DEBUG | pool-2-thread-2 | sql |
.utils.LoggingStatementWrapper 756 | bound (2,
ProcessCompletionEvent:
Type = instanceLifecycle
ProcessInstanceId = 1053
ProcessId =
{http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL-12
ProcessName =
{http://diashabiles.interoperatividad.ignos.com/bpel}procesoPruebaBPEL
Tim) (4,null) (8,1001) (6,ProcessCompletionEvent) (1,1077) (3,[EMAIL
PROTECTED])
(7,1053) (5,2007-11-27 11:38:53.538)
11:38:54,674 | DEBUG | pool-2-thread-2 | sql |
utils.LoggingConnectionWrapper 210 | close
11:38:57,255 | DEBUG | OdeScheduler | SimpleScheduler |
impleScheduler$CheckStaleNodes 568 | CHECK STALE NODES started
11:39:07,261 | DEBUG | OdeScheduler | SimpleScheduler |
impleScheduler$CheckStaleNodes 568 | CHECK STALE NODES started
imorales wrote:
>
> No I dont, I havent any datasource I deploy the ode-jbi component with the
> default configuration properties values.
>
> Alex Boisvert wrote:
>>
>> Hi,
>>
>> This looks like a configuration issue related to your DataSource. Are
>> you
>> configuring your datasource in ServiceMix and doing a JNDI lookup to get
>> it?
>>
>> alex
>>
>>
>> On 11/26/07, imorales <[EMAIL PROTECTED]> wrote:
>>>
>>>
>>> Hi all. I´m using Servicemix 3.1.2 and ode-jbi 1.1. I have a bpel
>>> process
>>> that invokes a web service, the bpel process is listenin in a
>>> http:endpoint
>>> and when I try to call this endpoint I get next error:
>>>
>>> ERROR - OdeService - Could not find a mapper for
>>> request
>>> message for JBI MEX ID:192.168.1.142-1167bc06ebd-3:0; ODE MEX
>>> hqejbhcnphr2s0ankoof5v is failed.
>>> WARN - Transaction - Unexpected exception from
>>> beforeCompletion; transaction will roll back
>>> <0.9.7-incubating nonfatal general error>
>>> org.apache.openjpa.persistence.PersistenceException:
>>> java.sql.SQLException
>>> at
>>> org.apache.openjpa.jdbc.sql.DBDictionary.newStoreException(
>>> DBDictionary.java:3784)
>>> at
>>> org.apache.openjpa.jdbc.sql.SQLExceptions.getStore(SQLExceptions.java:97)
>>> at
>>> org.apache.openjpa.jdbc.sql.SQLExceptions.getStore(SQLExceptions.java:83)
>>> at
>>> org.apache.openjpa.jdbc.sql.SQLExceptions.getStore(SQLExceptions.java:59)
>>> at
>>> org.apache.openjpa.jdbc.kernel.JDBCStoreManager.connect(
>>> JDBCStoreManager.java:727)
>>> at
>>> org.apache.openjpa.jdbc.kernel.JDBCStoreManager.retainConnection(
>>> JDBCStoreManager.java:197)
>>> at
>>> org.apache.openjpa.kernel.DelegatingStoreManager.retainConnection(
>>> DelegatingStoreManager.java:163)
>>> at
>>> org.apache.openjpa.kernel.BrokerImpl.retainConnection(BrokerImpl.java
>>> :3662)
>>> at
>>> org.apache.openjpa.kernel.BrokerImpl.beginStoreManagerTransaction(
>>> BrokerImpl.java:1244)
>>> at org.apache.openjpa.kernel.BrokerImpl.flush(BrokerImpl.java
>>> :1923)
>>> at
>>> org.apache.openjpa.kernel.BrokerImpl.flushSafe(BrokerImpl.java:1863)
>>> at
>>> org.apache.openjpa.kernel.BrokerImpl.beforeCompletion(BrokerImpl.java
>>> :1781)
>>> at
>>> org.apache.geronimo.transaction.manager.TransactionImpl.beforeCompletion(
>>> TransactionImpl.java:515)
>>> at
>>> org.apache.geronimo.transaction.manager.TransactionImpl.rollback(
>>> TransactionImpl.java:471)
>>> at
>>> org.apache.geronimo.transaction.manager.TransactionManagerImpl.rollback(
>>> TransactionManagerImpl.java:276)
>>> at org.apache.ode.jbi.OdeService.invokeOde(OdeService.java:274)
>>> at
>>> org.apache.ode.jbi.OdeService.onJbiMessageExchange(OdeService.java:148)
>>> at
>>> org.apache.ode.jbi.JbiMessageExchangeEventRouter.onJbiMessageExchange(
>>> JbiMessageExchangeEventRouter.java:47)
>>> at org.apache.ode.jbi.Receiver$1.run(Receiver.java:179)
>>> at
>>> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)
>>> at
>>> java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
>>> at java.util.concurrent.FutureTask.run(FutureTask.java:123)
>>> at
>>> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(
>>> ThreadPoolExecutor.java:650)
>>> at
>>> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java
>>> :675)
>>> at java.lang.Thread.run(Thread.java:595)
>>> Caused by: java.sql.SQLException
>>> at
>>> org.tranql.connector.jdbc.DataSource.getConnection(DataSource.java:61)
>>> at
>>> org.apache.ode.utils.LoggingDataSourceWrapper.getConnection(
>>> LoggingDataSourceWrapper.java:42)
>>> at
>>> org.apache.openjpa.lib.jdbc.DelegatingDataSource.getConnection(
>>> DelegatingDataSource.java:106)
>>> at
>>> org.apache.openjpa.lib.jdbc.DecoratingDataSource.getConnection(
>>> DecoratingDataSource.java:87)
>>> at
>>> org.apache.openjpa.jdbc.kernel.JDBCStoreManager.connectInternal(
>>> JDBCStoreManager.java:738)
>>> at
>>> org.apache.openjpa.jdbc.kernel.JDBCStoreManager.connect(
>>> JDBCStoreManager.java:723)
>>> ... 20 more
>>> Caused by: javax.resource.ResourceException: Could not enlist resource
>>> in
>>> rolled back transaction
>>> at
>>>
>>> org.apache.geronimo.connector.outbound.TransactionEnlistingInterceptor.getConnection
>>> (TransactionEnlistingInterceptor.java:62)
>>> at
>>>
>>> org.apache.geronimo.connector.outbound.TransactionCachingInterceptor.getConnection
>>> (TransactionCachingInterceptor.java:87)
>>> at
>>>
>>> org.apache.geronimo.connector.outbound.ConnectionHandleInterceptor.getConnection
>>> (ConnectionHandleInterceptor.java:43)
>>> at
>>> org.apache.geronimo.connector.outbound.TCCLInterceptor.getConnection(
>>> TCCLInterceptor.java:39)
>>> at
>>>
>>> org.apache.geronimo.connector.outbound.AbstractConnectionManager.allocateConnection
>>> (AbstractConnectionManager.java:61)
>>> at
>>> org.tranql.connector.jdbc.DataSource.getConnection(DataSource.java:56)
>>> ... 25 more
>>> Caused by: javax.transaction.RollbackException: Transaction is marked
>>> for
>>> rollback
>>> at
>>> org.apache.geronimo.transaction.manager.TransactionImpl.enlistResource(
>>> TransactionImpl.java:167)
>>> at
>>>
>>> org.apache.geronimo.connector.outbound.TransactionEnlistingInterceptor.getConnection
>>> (TransactionEnlistingInterceptor.java:54)
>>> ... 30 more
>>>
>>>
>>> The BPEL code is:
>>> .
>>> ..
>>> ...
>>>
>>> <bpws:sequence name="Main">
>>>
>>> <bpws:receive name="diaHabilReceive"
>>> createInstance="yes"
>>> operation="esDiaHabilAhoraBPEL"
>>> partnerLink="DiasHabilesPartnerLink"
>>> portType="da:DiasHabilesService"
>>> variable="esDiaHabilAhoraRequest"/>
>>>
>>> <bpws:assign>
>>> <bpws:copy>
>>> <bpws:from>$esDiaHabilAhoraRequest.parameters</bpws:from>
>>> <bpws:to>$esDiaHabilAhoraWsRequest.parameters</bpws:to>
>>> </bpws:copy>
>>> </bpws:assign>
>>>
>>>
>>> <bpws:invoke
>>> partnerLink="DiasHabilesWsPartnerLink"
>>> portType="daws:DiasHabilesService"
>>> operation="esDiaHabilAhora"
>>> inputVariable="esDiaHabilAhoraWsRequest"
>>> outputVariable="esDiaHabilAhoraWsResponse"
>>> />
>>>
>>> ...
>>> ..
>>> .
>>>
>>> Any ideas ? thanks in advance.
>>> --
>>> View this message in context:
>>> http://www.nabble.com/Servicemix-Error-invoking-ODE-tf4874789s12049.html#a13948468
>>> Sent from the ServiceMix - User mailing list archive at Nabble.com.
>>>
>>>
>>
>>
>
>
--
View this message in context:
http://www.nabble.com/Servicemix-Error-invoking-ODE-tf4874789s12049.html#a13969170
Sent from the ServiceMix - User mailing list archive at Nabble.com.
