You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users@servicemix.apache.org by imorales <im...@ignos.com> on 2007/11/26 13:01:57 UTC

Servicemix Error invoking ODE

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.


Re: Servicemix Error invoking ODE

Posted by Jan Arend Jansen <no...@xs4all.nl>.
Alex,

I have this ODE datasource problem too. I tried to add the following to
servicemix/conf/jndi.xml:

<entry key="java:comp/env/jdbc/ode">
   <bean id="derby-ds" class="org.apache.derby.jdbc.EmbeddedDataSource">
    <property name="databaseName" value="derbydb"/>
    <property name="createDatabase" value="create"/>
  </bean>
</entry>

And I have updated the ode-jbi.properties to
ode-jbi.db.mode=EXTERNAL

Now it seems that the database is found, but the schema is not autocreated:
Caused by: org.apache.openjpa.lib.jdbc.ReportingSQLException: Table/View
'STORE_DU' does not exist. {SELECT t0.NAME, t0.DEPLOYDT, t0.DEPLOYER, t0.DIR
FROM STORE_DU t0} [code=30000, state=42X05]

Any thoughts?



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
> 



-- 
View this message in context: http://www.nabble.com/Servicemix-Error-invoking-ODE-tf4874789s12049.html#a13952438
Sent from the ServiceMix - User mailing list archive at Nabble.com.


Re: Servicemix Error invoking ODE

Posted by imorales <im...@ignos.com>.
I saw in the ode-jbi.properties file the default configuration values. The
"ode-jbi.db.mode=EMBEDDED" its to use internal Derby database but in the the
next values of 
"#ode-jbi.db.emb.name=hibdb"
"#ode-jbi.db.emb.name=jpadb"
They are commnets, the properties are needed to mode embebded ?


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 <im...@ignos.com> 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#a13951884
Sent from the ServiceMix - User mailing list archive at Nabble.com.


Re: Servicemix Error invoking ODE

Posted by imorales <im...@ignos.com>.
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 |
org.apache.servicemix.jbi.nmr.flow.seda.SedaQueue$1@165391b 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,[B@bbef70) (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,[B@1c480e8) (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,[B@1a1a1c6) (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,[B@25352b) (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,[B@18430a5) (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,[B@ccadbd) (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,[B@175beef) (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,[B@1933d6c) (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,[B@173b35d) (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,[B@896e10) (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,[B@d9cc1a) (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,[B@1263a93) (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,[B@f0756d) (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,[B@1c882db) (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,[B@19bb448) (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,[B@de1f47) (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,[B@1020230) (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,[B@15b5219) (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,[B@a7f8a4) (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,[B@12fcbd8)
(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 <im...@ignos.com> 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.


Re: Servicemix Error invoking ODE

Posted by imorales <im...@ignos.com>.
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 <im...@ignos.com> 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#a13951380
Sent from the ServiceMix - User mailing list archive at Nabble.com.


Re: Servicemix Error invoking ODE

Posted by Alex Boisvert <bo...@intalio.com>.
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 <im...@ignos.com> 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.
>
>