ode-user mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From René Bos <r....@pagelink.nl>
Subject RE: Running Ode using an external database (MySql)
Date Fri, 29 Jun 2007 15:01:27 GMT
I did that now. I will post the log below! I can't add it as an attachment because the message is marked as spam than most of the times.

Note that there are some errors first about removed processes, I'm calling a not removed process so that that is not the problem.. ;)

René

29-jun-2007 16:56:12 org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: The Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: c:\jdk1.5.0_11\bin;.;C:\WINNT\system32;C:\WINNT;c:\ruby\bin;.;c:\ant\bin;C:\Program Files\TextPad 4;C:\Program Files\Apache Software Foundation\;C:\Maven\bin;c:\
winnt\system32;C:\Program Files\QuickTime\QTSystem\;C:\Program Files\TortoiseCVS\;c:\maven-2.0\bin;c:\jre1.5.0_11\bin;c:\jdk1.5.0_11\bin
29-jun-2007 16:56:13 org.apache.coyote.http11.Http11BaseProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-8080
29-jun-2007 16:56:13 org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 2623 ms
29-jun-2007 16:56:13 org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
29-jun-2007 16:56:13 org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/5.5.20
29-jun-2007 16:56:13 org.apache.catalina.core.StandardHost start
INFO: XML validation disabled
29-jun-2007 16:56:15 org.apache.catalina.startup.HostConfig deployDescriptor
WARNING: A docBase C:\apache-tomcat-5.5.20-ode-2007-06-20\webapps\ode inside the host appBase has been specified, and will be ignored
DEBUG - GeronimoLog.debug(66) | Setting base working directory: C:\apache-tomcat-5.5.20-ode-2007-06-20\webapps\ode\WEB-INF
16:56:20,439 DEBUG [TempFileManager] Setting base working directory: C:\apache-tomcat-5.5.20-ode-2007-06-20\webapps\ode\WEB-INF
DEBUG - GeronimoLog.debug(66) | Loading properties
16:56:20,449 DEBUG [ODEServer] Loading properties
DEBUG - GeronimoLog.debug(66) | config file exists: C:\apache-tomcat-5.5.20-ode-2007-06-20\webapps\ode\WEB-INF\conf\ode-axis2.properties
16:56:20,449 DEBUG [OdeConfigProperties] config file exists: C:\apache-tomcat-5.5.20-ode-2007-06-20\webapps\ode\WEB-INF\conf\ode-axis2.properties
DEBUG - GeronimoLog.debug(66) | Initializing transaction manager
16:56:20,449 DEBUG [ODEServer] Initializing transaction manager
DEBUG - GeronimoLog.debug(66) | Initializing transaction manager using org.apache.ode.il.EmbeddedGeronimoFactory
16:56:20,459 DEBUG [ODEServer] Initializing transaction manager using org.apache.ode.il.EmbeddedGeronimoFactory
INFO - GeronimoLog.info(79) | Using embedded Geronimo transaction manager
16:56:20,459 INFO  [EmbeddedGeronimoFactory] Using embedded Geronimo transaction manager
DEBUG - GeronimoLog.debug(66) | Creating data source.
16:56:20,659 DEBUG [ODEServer] Creating data source.
INFO - GeronimoLog.info(79) | ODE using external DataSource "java:comp/env/jdbc/OdeDB".
16:56:20,709 INFO  [Database] ODE using external DataSource "java:comp/env/jdbc/OdeDB".
DEBUG - GeronimoLog.debug(66) | Starting DAO.
16:56:20,709 DEBUG [ODEServer] Starting DAO.
INFO - GeronimoLog.info(79) | Using DAO Connection Factory class org.apache.ode.dao.jpa.BPELDAOConnectionFactoryImpl.
16:56:20,709 INFO  [ODEServer] Using DAO Connection Factory class org.apache.ode.dao.jpa.BPELDAOConnectionFactoryImpl.
INFO - GeronimoLog.info(79) | Using DAO Connection Factory class org.apache.ode.dao.jpa.BPELDAOConnectionFactoryImpl.
16:56:20,719 INFO  [Database] Using DAO Connection Factory class org.apache.ode.dao.jpa.BPELDAOConnectionFactoryImpl.
DEBUG - GeronimoLog.debug(66) | DAO started.
16:56:24,134 DEBUG [ODEServer] DAO started.
DEBUG - GeronimoLog.debug(66) | Initializing BPEL process store.
16:56:24,134 DEBUG [ODEServer] Initializing BPEL process store.
DEBUG - GeronimoLog.debug(66) | Registering listener org.apache.ode.axis2.ODEServer$ProcessStoreListenerImpl@25491d
16:56:24,264 DEBUG [ProcessStoreImpl] Registering listener org.apache.ode.axis2.ODEServer$ProcessStoreListenerImpl@25491d
DEBUG - GeronimoLog.debug(66) | Initializing BPEL server.
16:56:24,264 DEBUG [ODEServer] Initializing BPEL server.
DEBUG - GeronimoLog.debug(66) | ODE initializing
16:56:24,264 DEBUG [ODEServer] ODE initializing
DEBUG - GeronimoLog.debug(66) | getConnection (tx=4)
16:56:26,026 DEBUG [sql] getConnection (tx=4)
DEBUG - GeronimoLog.debug(66) | close
16:56:26,036 DEBUG [sql] close
INFO - GeronimoLog.info(79) | Using thread monitor-based data access locking (synchronization).
16:56:26,186 INFO  [JobStoreJTA] Using thread monitor-based data access locking (synchronization).
DEBUG - GeronimoLog.debug(66) | getNonManagedTXConnection()
16:56:26,196 DEBUG [JobStoreJTA] getNonManagedTXConnection()
DEBUG - GeronimoLog.debug(66) | getConnection (tx=4)
16:56:26,256 DEBUG [sql] getConnection (tx=4)
DEBUG - GeronimoLog.debug(66) | Setting isolation level to 2
16:56:26,266 DEBUG [sql] Setting isolation level to 2
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is desired by: main
16:56:26,266 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is desired by: main
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is being obtained: main
16:56:26,266 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is being obtained: main
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' given to: main
16:56:26,276 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' given to: main
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is desired by: main
16:56:26,286 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is desired by: main
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is being obtained: main
16:56:26,286 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is being obtained: main
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' given to: main
16:56:26,286 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' given to: main
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE IS_VOLATILE = ?
16:56:26,296 DEBUG [JobStoreJTA] prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE IS_VOLATILE = ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE IS_VOLATILE = ?
16:56:26,306 DEBUG [sql] prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE IS_VOLATILE = ?
DEBUG - GeronimoLog.debug(66) | bound (1,true)
16:56:26,336 DEBUG [JobStoreJTA] bound (1,true)
DEBUG - GeronimoLog.debug(66) | bound (1,true)
16:56:26,336 DEBUG [sql] bound (1,true)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT JOB_NAME, JOB_GROUP FROM QRTZ_JOB_DETAILS WHERE IS_VOLATILE = ?
16:56:26,346 DEBUG [JobStoreJTA] prepareStmt: SELECT JOB_NAME, JOB_GROUP FROM QRTZ_JOB_DETAILS WHERE IS_VOLATILE = ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT JOB_NAME, JOB_GROUP FROM QRTZ_JOB_DETAILS WHERE IS_VOLATILE = ?
16:56:26,356 DEBUG [sql] prepareStmt: SELECT JOB_NAME, JOB_GROUP FROM QRTZ_JOB_DETAILS WHERE IS_VOLATILE = ?
DEBUG - GeronimoLog.debug(66) | bound (1,true)
16:56:26,356 DEBUG [JobStoreJTA] bound (1,true)
DEBUG - GeronimoLog.debug(66) | bound (1,true)
16:56:26,366 DEBUG [sql] bound (1,true)
INFO - GeronimoLog.info(79) | Removed 0 Volatile Trigger(s).
16:56:26,366 INFO  [JobStoreJTA] Removed 0 Volatile Trigger(s).
INFO - GeronimoLog.info(79) | Removed 0 Volatile Job(s).
16:56:26,376 INFO  [JobStoreJTA] Removed 0 Volatile Job(s).
DEBUG - GeronimoLog.debug(66) | prepareStmt: DELETE FROM QRTZ_FIRED_TRIGGERS WHERE IS_VOLATILE = ?
16:56:26,376 DEBUG [JobStoreJTA] prepareStmt: DELETE FROM QRTZ_FIRED_TRIGGERS WHERE IS_VOLATILE = ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: DELETE FROM QRTZ_FIRED_TRIGGERS WHERE IS_VOLATILE = ?
16:56:26,386 DEBUG [sql] prepareStmt: DELETE FROM QRTZ_FIRED_TRIGGERS WHERE IS_VOLATILE = ?
DEBUG - GeronimoLog.debug(66) | bound (1,true)
16:56:26,386 DEBUG [JobStoreJTA] bound (1,true)
DEBUG - GeronimoLog.debug(66) | bound (1,true)
16:56:26,396 DEBUG [sql] bound (1,true)
DEBUG - GeronimoLog.debug(66) | COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@2f1e75
16:56:26,436 DEBUG [JobStoreJTA] COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@2f1e75
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' retuned by: main
16:56:26,436 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' retuned by: main
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' retuned by: main
16:56:26,447 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' retuned by: main
DEBUG - GeronimoLog.debug(66) | close
16:56:26,447 DEBUG [JobStoreJTA] close
DEBUG - GeronimoLog.debug(66) | close
16:56:26,447 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | BPEL SERVER initializing
16:56:26,497 DEBUG [BpelServerImpl] BPEL SERVER initializing
DEBUG - GeronimoLog.debug(66) | BPEL SERVER starting.
16:56:26,537 DEBUG [BpelServerImpl] BPEL SERVER starting.
DEBUG - GeronimoLog.debug(66) | getNonManagedTXConnection()
16:56:26,547 DEBUG [JobStoreJTA] getNonManagedTXConnection()
DEBUG - GeronimoLog.debug(66) | getNonManagedTXConnection()
16:56:26,547 DEBUG [JobStoreJTA] getNonManagedTXConnection()
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:56:26,567 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | Setting isolation level to 2
16:56:26,577 DEBUG [sql] Setting isolation level to 2
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is desired by: main
16:56:26,587 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is desired by: main
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is being obtained: main
16:56:26,587 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is being obtained: main
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' given to: main
16:56:26,597 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' given to: main
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is desired by: main
16:56:26,597 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is desired by: main
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is being obtained: main
16:56:26,607 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is being obtained: main
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' given to: main
16:56:26,607 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' given to: main
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE TRIGGER_STATE = ? OR TRIGGER_STATE = ?
16:56:26,617 DEBUG [JobStoreJTA] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE TRIGGER_STATE = ? OR TRIGGER_STATE = ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE TRIGGER_STATE = ? OR TRIGGER_STATE = ?
16:56:26,617 DEBUG [sql] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE TRIGGER_STATE = ? OR TRIGGER_STATE = ?
DEBUG - GeronimoLog.debug(66) | bound (2,ACQUIRED) (1,WAITING) (3,BLOCKED)
16:56:26,617 DEBUG [JobStoreJTA] bound (2,ACQUIRED) (1,WAITING) (3,BLOCKED)
DEBUG - GeronimoLog.debug(66) | bound (2,ACQUIRED) (1,WAITING) (3,BLOCKED)
16:56:26,627 DEBUG [sql] bound (2,ACQUIRED) (1,WAITING) (3,BLOCKED)
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE TRIGGER_STATE = ? OR TRIGGER_STATE = ?
16:56:26,637 DEBUG [JobStoreJTA] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE TRIGGER_STATE = ? OR TRIGGER_STATE = ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE TRIGGER_STATE = ? OR TRIGGER_STATE = ?
16:56:26,637 DEBUG [sql] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE TRIGGER_STATE = ? OR TRIGGER_STATE = ?
DEBUG - GeronimoLog.debug(66) | bound (2,PAUSED_BLOCKED) (1,PAUSED) (3,PAUSED_BLOCKED)
16:56:26,637 DEBUG [JobStoreJTA] bound (2,PAUSED_BLOCKED) (1,PAUSED) (3,PAUSED_BLOCKED)
DEBUG - GeronimoLog.debug(66) | bound (2,PAUSED_BLOCKED) (1,PAUSED) (3,PAUSED_BLOCKED)
16:56:26,647 DEBUG [sql] bound (2,PAUSED_BLOCKED) (1,PAUSED) (3,PAUSED_BLOCKED)
INFO - GeronimoLog.info(79) | Freed 0 triggers from 'acquired' / 'blocked' state.
16:56:26,647 INFO  [JobStoreJTA] Freed 0 triggers from 'acquired' / 'blocked' state.
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
16:56:26,657 DEBUG [JobStoreJTA] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
16:56:26,657 DEBUG [sql] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
DEBUG - GeronimoLog.debug(66) | bound (2,WAITING) (4,1183128926657) (1,MISFIRED) (3,WAITING)
16:56:26,667 DEBUG [JobStoreJTA] bound (2,WAITING) (4,1183128926657) (1,MISFIRED) (3,WAITING)
DEBUG - GeronimoLog.debug(66) | bound (2,WAITING) (4,1183128926657) (1,MISFIRED) (3,WAITING)
16:56:26,667 DEBUG [sql] bound (2,WAITING) (4,1183128926657) (1,MISFIRED) (3,WAITING)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
16:56:26,677 DEBUG [JobStoreJTA] prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
16:56:26,677 DEBUG [sql] prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
DEBUG - GeronimoLog.debug(66) | bound (1,MISFIRED)
16:56:26,687 DEBUG [JobStoreJTA] bound (1,MISFIRED)
DEBUG - GeronimoLog.debug(66) | bound (1,MISFIRED)
16:56:26,687 DEBUG [sql] bound (1,MISFIRED)
DEBUG - GeronimoLog.debug(66) | Found 0 triggers that missed their scheduled fire-time.
16:56:26,697 DEBUG [JobStoreJTA] Found 0 triggers that missed their scheduled fire-time.
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT * FROM QRTZ_FIRED_TRIGGERS WHERE INSTANCE_NAME = ? AND REQUESTS_RECOVERY = ?
16:56:26,697 DEBUG [JobStoreJTA] prepareStmt: SELECT * FROM QRTZ_FIRED_TRIGGERS WHERE INSTANCE_NAME = ? AND REQUESTS_RECOVERY = ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT * FROM QRTZ_FIRED_TRIGGERS WHERE INSTANCE_NAME = ? AND REQUESTS_RECOVERY = ?
16:56:26,707 DEBUG [sql] prepareStmt: SELECT * FROM QRTZ_FIRED_TRIGGERS WHERE INSTANCE_NAME = ? AND REQUESTS_RECOVERY = ?
DEBUG - GeronimoLog.debug(66) | bound (2,true) (1,hqejbhcnphr2e149g9im9p)
16:56:26,707 DEBUG [JobStoreJTA] bound (2,true) (1,hqejbhcnphr2e149g9im9p)
DEBUG - GeronimoLog.debug(66) | bound (2,true) (1,hqejbhcnphr2e149g9im9p)
16:56:26,707 DEBUG [sql] bound (2,true) (1,hqejbhcnphr2e149g9im9p)
INFO - GeronimoLog.info(79) | Recovering 0 jobs that were in-progress at the time of the last shut-down.
16:56:26,717 INFO  [JobStoreJTA] Recovering 0 jobs that were in-progress at the time of the last shut-down.
INFO - GeronimoLog.info(79) | Recovery complete.
16:56:26,727 INFO  [JobStoreJTA] Recovery complete.
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
16:56:26,727 DEBUG [JobStoreJTA] prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
16:56:26,727 DEBUG [sql] prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
DEBUG - GeronimoLog.debug(66) | bound (1,COMPLETE)
16:56:26,737 DEBUG [JobStoreJTA] bound (1,COMPLETE)
DEBUG - GeronimoLog.debug(66) | bound (1,COMPLETE)
16:56:26,737 DEBUG [sql] bound (1,COMPLETE)
INFO - GeronimoLog.info(79) | Removed 0 'complete' triggers.
16:56:26,747 INFO  [JobStoreJTA] Removed 0 'complete' triggers.
DEBUG - GeronimoLog.debug(66) | prepareStmt: DELETE FROM QRTZ_FIRED_TRIGGERS
16:56:26,747 DEBUG [JobStoreJTA] prepareStmt: DELETE FROM QRTZ_FIRED_TRIGGERS
DEBUG - GeronimoLog.debug(66) | prepareStmt: DELETE FROM QRTZ_FIRED_TRIGGERS
16:56:26,757 DEBUG [sql] prepareStmt: DELETE FROM QRTZ_FIRED_TRIGGERS
INFO - GeronimoLog.info(79) | Removed 0 stale fired job entries.
16:56:26,757 INFO  [JobStoreJTA] Removed 0 stale fired job entries.
DEBUG - GeronimoLog.debug(66) | COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@d337d3
16:56:26,767 DEBUG [JobStoreJTA] COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@d337d3
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' retuned by: main
16:56:26,767 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' retuned by: main
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' retuned by: main
16:56:26,767 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' retuned by: main
DEBUG - GeronimoLog.debug(66) | close
16:56:26,777 DEBUG [JobStoreJTA] close
DEBUG - GeronimoLog.debug(66) | close
16:56:26,777 DEBUG [sql] close
INFO - GeronimoLog.info(79) | BPEL Server Started.
16:56:26,787 INFO  [BpelServerImpl] BPEL Server Started.
DEBUG - GeronimoLog.debug(66) | MisfireHandler: scanning for misfires...
16:56:26,837 DEBUG [JobStoreJTA] MisfireHandler: scanning for misfires...
DEBUG - GeronimoLog.debug(66) | doRecoverMisfires() callled
16:56:26,837 DEBUG [JobStoreJTA] doRecoverMisfires() callled
DEBUG - GeronimoLog.debug(66) | getNonManagedTXConnection()
16:56:26,837 DEBUG [JobStoreJTA] getNonManagedTXConnection()
DEBUG - GeronimoLog.debug(66) | getConnection (tx=4)
16:56:26,987 DEBUG [sql] getConnection (tx=4)
DEBUG - GeronimoLog.debug(66) | Setting isolation level to 2
16:56:26,997 DEBUG [sql] Setting isolation level to 2
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is desired by: ODE_QuartzSchedulerThread
16:56:26,997 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is desired by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is being obtained: ODE_QuartzSchedulerThread
16:56:26,997 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is being obtained: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' given to: ODE_QuartzSchedulerThread
16:56:27,007 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' given to: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is desired by: ODE_QuartzSchedulerThread
16:56:27,007 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is desired by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is being obtained: ODE_QuartzSchedulerThread
16:56:27,007 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is being obtained: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' given to: ODE_QuartzSchedulerThread
16:56:27,017 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' given to: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
16:56:27,017 DEBUG [JobStoreJTA] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
16:56:27,017 DEBUG [sql] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
DEBUG - GeronimoLog.debug(66) | bound (2,WAITING) (4,1183128927017) (1,MISFIRED) (3,WAITING)
16:56:27,027 DEBUG [JobStoreJTA] bound (2,WAITING) (4,1183128927017) (1,MISFIRED) (3,WAITING)
DEBUG - GeronimoLog.debug(66) | bound (2,WAITING) (4,1183128927017) (1,MISFIRED) (3,WAITING)
16:56:27,037 DEBUG [sql] bound (2,WAITING) (4,1183128927017) (1,MISFIRED) (3,WAITING)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
16:56:27,037 DEBUG [JobStoreJTA] prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
16:56:27,037 DEBUG [sql] prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
DEBUG - GeronimoLog.debug(66) | bound (1,WAITING)
16:56:27,047 DEBUG [JobStoreJTA] bound (1,WAITING)
DEBUG - GeronimoLog.debug(66) | bound (1,WAITING)
16:56:27,047 DEBUG [sql] bound (1,WAITING)
DEBUG - GeronimoLog.debug(66) | COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@a36b53
16:56:27,057 DEBUG [JobStoreJTA] COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@a36b53
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' retuned by: ODE_QuartzSchedulerThread
16:56:27,057 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' retuned by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' retuned by: ODE_QuartzSchedulerThread
16:56:27,057 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' retuned by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | close
16:56:27,067 DEBUG [JobStoreJTA] close
DEBUG - GeronimoLog.debug(66) | close
16:56:27,067 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:56:27,077 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | Setting isolation level to 2
16:56:27,077 DEBUG [sql] Setting isolation level to 2
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is desired by: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
16:56:27,087 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is desired by: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is being obtained: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
16:56:27,087 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is being obtained: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' given to: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
16:56:27,097 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' given to: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
16:56:27,097 DEBUG [JobStoreJTA] prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
16:56:27,107 DEBUG [sql] prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
DEBUG - GeronimoLog.debug(66) | bound (1,MISFIRED)
16:56:27,107 DEBUG [JobStoreJTA] bound (1,MISFIRED)
DEBUG - GeronimoLog.debug(66) | bound (1,MISFIRED)
16:56:27,107 DEBUG [sql] bound (1,MISFIRED)
DEBUG - GeronimoLog.debug(66) | Found 0 triggers that missed their scheduled fire-time.
16:56:27,117 DEBUG [JobStoreJTA] Found 0 triggers that missed their scheduled fire-time.
DEBUG - GeronimoLog.debug(66) | COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@1c4a5ec
16:56:27,117 DEBUG [JobStoreJTA] COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@1c4a5ec
DEBUG - GeronimoLog.debug(66) | doRecoverMisfires() returned moreToDo = false
16:56:27,127 DEBUG [JobStoreJTA] doRecoverMisfires() returned moreToDo = false
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' retuned by: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
16:56:27,127 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' retuned by: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
DEBUG - GeronimoLog.debug(66) | close
16:56:27,137 DEBUG [JobStoreJTA] close
DEBUG - GeronimoLog.debug(66) | close
16:56:27,137 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:56:29,571 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | close
16:56:29,701 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:56:31,753 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT t0.NAME, t0.DEPLOYDT, t0.DEPLOYER, t0.DIR FROM STORE_DU t0
16:56:31,773 DEBUG [sql] prepareStmt: SELECT t0.NAME, t0.DEPLOYDT, t0.DEPLOYER, t0.DIR FROM STORE_DU t0
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT t0.NAME, t1.PID, t1.STATE, t1.TYPE, t1.VERSION FROM STORE_DU t0 INNER JOIN STORE_PROCESS t1 ON t0.NAME = t1.DU ORDER BY t0.NAME ASC
16:56:31,793 DEBUG [sql] prepareStmt: SELECT t0.NAME, t1.PID, t1.STATE, t1.TYPE, t1.VERSION FROM STORE_DU t0 INNER JOIN STORE_PROCESS t1 ON t0.NAME = t1.DU ORDER BY t0.NAME ASC
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT DISTINCT t1.PID, t3.id, t3.PROP_KEY, t3.PROP_VAL FROM STORE_DU t0 INNER JOIN STORE_PROCESS t1 ON t0.NAME = t1.DU INNER JOIN STORE_PROC_TO_PROP t2 ON t1.PID = t2.ProcessConfDaoImpl_PID INNER JOIN STORE_PROCESS_PROP t3 ON t2.element_id = t3.id ORDER
BY t1.PID ASC
16:56:31,843 DEBUG [sql] prepareStmt: SELECT DISTINCT t1.PID, t3.id, t3.PROP_KEY, t3.PROP_VAL FROM STORE_DU t0 INNER JOIN STORE_PROCESS t1 ON t0.NAME = t1.DU INNER JOIN STORE_PROC_TO_PROP t2 ON t1.PID = t2.ProcessConfDaoImpl_PID INNER JOIN STORE_PROCESS_PROP t3 ON t2.element_id = t3.id ORDER BY t1.P
ID ASC
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT t1.NAME, t1.DEPLOYDT, t1.DEPLOYER, t1.DIR FROM STORE_PROCESS t0 INNER JOIN STORE_DU t1 ON t0.DU = t1.NAME WHERE t0.PID = ?
16:56:32,024 DEBUG [sql] prepareStmt: SELECT t1.NAME, t1.DEPLOYDT, t1.DEPLOYER, t1.DIR FROM STORE_PROCESS t0 INNER JOIN STORE_DU t1 ON t0.DU = t1.NAME WHERE t0.PID = ?
DEBUG - GeronimoLog.debug(66) | bound (1,{http://demo_Bier}demo_Bier-6)
16:56:32,034 DEBUG [sql] bound (1,{http://demo_Bier}demo_Bier-6)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT t1.NAME, t1.DEPLOYDT, t1.DEPLOYER, t1.DIR FROM STORE_PROCESS t0 INNER JOIN STORE_DU t1 ON t0.DU = t1.NAME WHERE t0.PID = ?
16:56:32,044 DEBUG [sql] prepareStmt: SELECT t1.NAME, t1.DEPLOYDT, t1.DEPLOYER, t1.DIR FROM STORE_PROCESS t0 INNER JOIN STORE_DU t1 ON t0.DU = t1.NAME WHERE t0.PID = ?
DEBUG - GeronimoLog.debug(66) | bound (1,{http://demo_Bier}demo_Bier-7)
16:56:32,054 DEBUG [sql] bound (1,{http://demo_Bier}demo_Bier-7)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT t1.NAME, t1.DEPLOYDT, t1.DEPLOYER, t1.DIR FROM STORE_PROCESS t0 INNER JOIN STORE_DU t1 ON t0.DU = t1.NAME WHERE t0.PID = ?
16:56:32,064 DEBUG [sql] prepareStmt: SELECT t1.NAME, t1.DEPLOYDT, t1.DEPLOYER, t1.DIR FROM STORE_PROCESS t0 INNER JOIN STORE_DU t1 ON t0.DU = t1.NAME WHERE t0.PID = ?
DEBUG - GeronimoLog.debug(66) | bound (1,{http://demo_T-Rex}demo_T-Rex-5)
16:56:32,074 DEBUG [sql] bound (1,{http://demo_T-Rex}demo_T-Rex-5)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT t1.NAME, t1.DEPLOYDT, t1.DEPLOYER, t1.DIR FROM STORE_PROCESS t0 INNER JOIN STORE_DU t1 ON t0.DU = t1.NAME WHERE t0.PID = ?
16:56:32,084 DEBUG [sql] prepareStmt: SELECT t1.NAME, t1.DEPLOYDT, t1.DEPLOYER, t1.DIR FROM STORE_PROCESS t0 INNER JOIN STORE_DU t1 ON t0.DU = t1.NAME WHERE t0.PID = ?
DEBUG - GeronimoLog.debug(66) | bound (1,{http://demo_testje}demo_testje-8)
16:56:32,084 DEBUG [sql] bound (1,{http://demo_testje}demo_testje-8)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT t1.NAME, t1.DEPLOYDT, t1.DEPLOYER, t1.DIR FROM STORE_PROCESS t0 INNER JOIN STORE_DU t1 ON t0.DU = t1.NAME WHERE t0.PID = ?
16:56:32,094 DEBUG [sql] prepareStmt: SELECT t1.NAME, t1.DEPLOYDT, t1.DEPLOYER, t1.DIR FROM STORE_PROCESS t0 INNER JOIN STORE_DU t1 ON t0.DU = t1.NAME WHERE t0.PID = ?
DEBUG - GeronimoLog.debug(66) | bound (1,{http://OPM_modifyObject}OPM_modifyObject-4)
16:56:32,094 DEBUG [sql] bound (1,{http://OPM_modifyObject}OPM_modifyObject-4)
DEBUG - GeronimoLog.debug(66) | close
16:56:32,104 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | Loading deployment unit record from db: demo_Bier-6
16:56:32,124 DEBUG [ProcessStoreImpl] Loading deployment unit record from db: demo_Bier-6
ERROR - GeronimoLog.error(108) | Error loading DU from store: demo_Bier-6
org.apache.ode.bpel.iapi.ContextException: Deployed directory null no longer there!
        at org.apache.ode.store.ProcessStoreImpl.load(ProcessStoreImpl.java:600)
        at org.apache.ode.store.ProcessStoreImpl$6.call(ProcessStoreImpl.java:455)
        at org.apache.ode.store.ProcessStoreImpl$Callable.call(ProcessStoreImpl.java:695)
        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)
16:56:32,144 ERROR [ProcessStoreImpl] Error loading DU from store: demo_Bier-6
org.apache.ode.bpel.iapi.ContextException: Deployed directory null no longer there!
        at org.apache.ode.store.ProcessStoreImpl.load(ProcessStoreImpl.java:600)
        at org.apache.ode.store.ProcessStoreImpl$6.call(ProcessStoreImpl.java:455)
        at org.apache.ode.store.ProcessStoreImpl$Callable.call(ProcessStoreImpl.java:695)
        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)
DEBUG - GeronimoLog.debug(66) | Loading deployment unit record from db: demo_Bier-7
16:56:32,174 DEBUG [ProcessStoreImpl] Loading deployment unit record from db: demo_Bier-7
ERROR - GeronimoLog.error(108) | Error loading DU from store: demo_Bier-7
org.apache.ode.bpel.iapi.ContextException: Deployed directory null no longer there!
        at org.apache.ode.store.ProcessStoreImpl.load(ProcessStoreImpl.java:600)
        at org.apache.ode.store.ProcessStoreImpl$6.call(ProcessStoreImpl.java:455)
        at org.apache.ode.store.ProcessStoreImpl$Callable.call(ProcessStoreImpl.java:695)
        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)
16:56:32,174 ERROR [ProcessStoreImpl] Error loading DU from store: demo_Bier-7
org.apache.ode.bpel.iapi.ContextException: Deployed directory null no longer there!
        at org.apache.ode.store.ProcessStoreImpl.load(ProcessStoreImpl.java:600)
        at org.apache.ode.store.ProcessStoreImpl$6.call(ProcessStoreImpl.java:455)
        at org.apache.ode.store.ProcessStoreImpl$Callable.call(ProcessStoreImpl.java:695)
        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)
DEBUG - GeronimoLog.debug(66) | Loading deployment unit record from db: demo_T-Rex-5
16:56:32,204 DEBUG [ProcessStoreImpl] Loading deployment unit record from db: demo_T-Rex-5
ERROR - GeronimoLog.error(108) | Error loading DU from store: demo_T-Rex-5
org.apache.ode.bpel.iapi.ContextException: Deployed directory null no longer there!
        at org.apache.ode.store.ProcessStoreImpl.load(ProcessStoreImpl.java:600)
        at org.apache.ode.store.ProcessStoreImpl$6.call(ProcessStoreImpl.java:455)
        at org.apache.ode.store.ProcessStoreImpl$Callable.call(ProcessStoreImpl.java:695)
        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)
16:56:32,204 ERROR [ProcessStoreImpl] Error loading DU from store: demo_T-Rex-5
org.apache.ode.bpel.iapi.ContextException: Deployed directory null no longer there!
        at org.apache.ode.store.ProcessStoreImpl.load(ProcessStoreImpl.java:600)
        at org.apache.ode.store.ProcessStoreImpl$6.call(ProcessStoreImpl.java:455)
        at org.apache.ode.store.ProcessStoreImpl$Callable.call(ProcessStoreImpl.java:695)
        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)
DEBUG - GeronimoLog.debug(66) | Loading deployment unit record from db: demo_testje-8
16:56:32,234 DEBUG [ProcessStoreImpl] Loading deployment unit record from db: demo_testje-8
DEBUG - GeronimoLog.debug(66) | Processing <invoke> element for process {http://demo_testje}demo_testje: partnerlink processServerLink --> <xml-fragment name="pps:ProcessServerService" port="ProcessServerPort" xmlns:pps="urn:PageProcessorServer" xmlns:khws="urn:Kwaliteitshand
boekWebservice" xmlns:pns="http://demo_testje" xmlns:tmws="urn:TaskManagerWebservice" xmlns:wns="http://demo_testje.wsdl"/>
16:56:33,145 DEBUG [ProcessConfImpl] Processing <invoke> element for process {http://demo_testje}demo_testje: partnerlink processServerLink --> <xml-fragment name="pps:ProcessServerService" port="ProcessServerPort" xmlns:pps="urn:PageProcessorServer" xmlns:khws="urn:Kwaliteit
shandboekWebservice" xmlns:pns="http://demo_testje" xmlns:tmws="urn:TaskManagerWebservice" xmlns:wns="http://demo_testje.wsdl"/>
DEBUG - GeronimoLog.debug(66) | Processing <invoke> element for process {http://demo_testje}demo_testje: partnerlink kwaliteitshandboekWebserviceLink --> <xml-fragment name="khws:KwaliteitshandboekWebserviceService" port="KwaliteitshandboekWebservicePort" xmlns:khws="urn:Kwal
iteitshandboekWebservice" xmlns:pns="http://demo_testje" xmlns:pps="urn:PageProcessorServer" xmlns:tmws="urn:TaskManagerWebservice" xmlns:wns="http://demo_testje.wsdl"/>
16:56:33,165 DEBUG [ProcessConfImpl] Processing <invoke> element for process {http://demo_testje}demo_testje: partnerlink kwaliteitshandboekWebserviceLink --> <xml-fragment name="khws:KwaliteitshandboekWebserviceService" port="KwaliteitshandboekWebservicePort" xmlns:khws="urn
:KwaliteitshandboekWebservice" xmlns:pns="http://demo_testje" xmlns:pps="urn:PageProcessorServer" xmlns:tmws="urn:TaskManagerWebservice" xmlns:wns="http://demo_testje.wsdl"/>
DEBUG - GeronimoLog.debug(66) | Processing <invoke> element for process {http://demo_testje}demo_testje: partnerlink taskManagerWebserviceLink --> <xml-fragment name="tmws:TaskManagerWebserviceService" port="TaskManagerWebservicePort" xmlns:tmws="urn:TaskManagerWebservice" xm
lns:khws="urn:KwaliteitshandboekWebservice" xmlns:pns="http://demo_testje" xmlns:pps="urn:PageProcessorServer" xmlns:wns="http://demo_testje.wsdl"/>
16:56:33,175 DEBUG [ProcessConfImpl] Processing <invoke> element for process {http://demo_testje}demo_testje: partnerlink taskManagerWebserviceLink --> <xml-fragment name="tmws:TaskManagerWebserviceService" port="TaskManagerWebservicePort" xmlns:tmws="urn:TaskManagerWebservic
e" xmlns:khws="urn:KwaliteitshandboekWebservice" xmlns:pns="http://demo_testje" xmlns:pps="urn:PageProcessorServer" xmlns:wns="http://demo_testje.wsdl"/>
DEBUG - GeronimoLog.debug(66) | Processing <provide> element for process {http://demo_testje}demo_testje: partnerlink demo_testjeLink --> {http://demo_testje.wsdl}demo_testjeService : demo_testjePort
16:56:33,195 DEBUG [ProcessConfImpl] Processing <provide> element for process {http://demo_testje}demo_testje: partnerlink demo_testjeLink --> {http://demo_testje.wsdl}demo_testjeService : demo_testjePort
DEBUG - GeronimoLog.debug(66) | Loading deployment unit record from db: OPM_modifyObject
16:56:33,195 DEBUG [ProcessStoreImpl] Loading deployment unit record from db: OPM_modifyObject
DEBUG - GeronimoLog.debug(66) | Processing <invoke> element for process {http://OPM_modifyObject}OPM_modifyObject: partnerlink processServerLink --> <xml-fragment name="pps:ProcessServerService" port="ProcessServerPort" xmlns:pps="urn:PageProcessorServer" xmlns:pns="http://OPM_modifyObject" xmlns:wn
s="http://OPM_modifyObject.wsdl" xmlns:opmws="urn:KwaliteitshandboekWebservice" xmlns:tmws="urn:TaskManagerWebservice"/>
16:56:33,456 DEBUG [ProcessConfImpl] Processing <invoke> element for process {http://OPM_modifyObject}OPM_modifyObject: partnerlink processServerLink --> <xml-fragment name="pps:ProcessServerService" port="ProcessServerPort" xmlns:pps="urn:PageProcessorServer" xmlns:pns="http://OPM_modifyObject" xml
ns:wns="http://OPM_modifyObject.wsdl" xmlns:opmws="urn:KwaliteitshandboekWebservice" xmlns:tmws="urn:TaskManagerWebservice"/>
DEBUG - GeronimoLog.debug(66) | Processing <invoke> element for process {http://OPM_modifyObject}OPM_modifyObject: partnerlink OPMWebserviceLink --> <xml-fragment name="opmws:OPMWebserviceService" port="OPMWebservicePort" xmlns:opmws="urn:OPMWebservice" xmlns:pns="http://OPM_modifyObject" xmlns:wns=
"http://OPM_modifyObject.wsdl" xmlns:pps="urn:PageProcessorServer" xmlns:tmws="urn:TaskManagerWebservice"/>
16:56:33,466 DEBUG [ProcessConfImpl] Processing <invoke> element for process {http://OPM_modifyObject}OPM_modifyObject: partnerlink OPMWebserviceLink --> <xml-fragment name="opmws:OPMWebserviceService" port="OPMWebservicePort" xmlns:opmws="urn:OPMWebservice" xmlns:pns="http://OPM_modifyObject" xmlns
:wns="http://OPM_modifyObject.wsdl" xmlns:pps="urn:PageProcessorServer" xmlns:tmws="urn:TaskManagerWebservice"/>
DEBUG - GeronimoLog.debug(66) | Processing <invoke> element for process {http://OPM_modifyObject}OPM_modifyObject: partnerlink taskManagerWebserviceLink --> <xml-fragment name="tmws:TaskManagerWebserviceService" port="TaskManagerWebservicePort" xmlns:tmws="urn:TaskManagerWebservice" xmlns:pns="http:
//OPM_modifyObject" xmlns:wns="http://OPM_modifyObject.wsdl" xmlns:pps="urn:PageProcessorServer" xmlns:opmws="urn:KwaliteitshandboekWebservice"/>
16:56:33,486 DEBUG [ProcessConfImpl] Processing <invoke> element for process {http://OPM_modifyObject}OPM_modifyObject: partnerlink taskManagerWebserviceLink --> <xml-fragment name="tmws:TaskManagerWebserviceService" port="TaskManagerWebservicePort" xmlns:tmws="urn:TaskManagerWebservice" xmlns:pns="
http://OPM_modifyObject" xmlns:wns="http://OPM_modifyObject.wsdl" xmlns:pps="urn:PageProcessorServer" xmlns:opmws="urn:KwaliteitshandboekWebservice"/>
DEBUG - GeronimoLog.debug(66) | Processing <provide> element for process {http://OPM_modifyObject}OPM_modifyObject: partnerlink OPM_modifyObjectLink --> {http://OPM_modifyObject.wsdl}OPM_modifyObjectService : OPM_modifyObjectPort
16:56:33,486 DEBUG [ProcessConfImpl] Processing <provide> element for process {http://OPM_modifyObject}OPM_modifyObject: partnerlink OPM_modifyObjectLink --> {http://OPM_modifyObject.wsdl}OPM_modifyObjectService : OPM_modifyObjectPort
DEBUG - GeronimoLog.debug(66) | firing event: {ProcessStoreEvent#ACTVIATED:{http://demo_testje}demo_testje-8}
16:56:33,496 DEBUG [ProcessStoreImpl] firing event: {ProcessStoreEvent#ACTVIATED:{http://demo_testje}demo_testje-8}
DEBUG - GeronimoLog.debug(66) | Process store event: {ProcessStoreEvent#ACTVIATED:{http://demo_testje}demo_testje-8}
16:56:33,506 DEBUG [ODEServer] Process store event: {ProcessStoreEvent#ACTVIATED:{http://demo_testje}demo_testje-8}
DEBUG - GeronimoLog.trace(54) | unregister: {http://demo_testje}demo_testje-8
16:56:33,506 DEBUG [BpelServerImpl] unregister: {http://demo_testje}demo_testje-8
INFO - GeronimoLog.info(79) | Process {http://demo_testje}demo_testje-8 has been unregistered.
16:56:33,516 INFO  [BpelServerImpl] Process {http://demo_testje}demo_testje-8 has been unregistered.
DEBUG - GeronimoLog.debug(66) | register: {http://demo_testje}demo_testje-8
16:56:33,516 DEBUG [BpelServerImpl] register: {http://demo_testje}demo_testje-8
DEBUG - GeronimoLog.debug(66) | Registering process {http://demo_testje}demo_testje-8 with server.
16:56:33,526 DEBUG [BpelServerImpl] Registering process {http://demo_testje}demo_testje-8 with server.
DEBUG - GeronimoLog.debug(66) | Register process: serviceId={http://demo_testje.wsdl}demo_testjeService:demo_testjePort, process=BpelProcess[{http://demo_testje}demo_testje-8]
16:56:33,546 DEBUG [BpelEngineImpl] Register process: serviceId={http://demo_testje.wsdl}demo_testjeService:demo_testjePort, process=BpelProcess[{http://demo_testje}demo_testje-8]
DEBUG - GeronimoLog.debug(66) | Activating {http://demo_testje}demo_testje-8
16:56:33,566 DEBUG [BpelProcess] Activating {http://demo_testje}demo_testje-8
DEBUG - GeronimoLog.debug(66) | getImportInputSource: parent=demo_testje.wsdl, imprt=PageProcessorServer.wsdl, uri=PageProcessorServer.wsdl
16:56:33,766 DEBUG [WSDLLocatorImpl] getImportInputSource: parent=demo_testje.wsdl, imprt=PageProcessorServer.wsdl, uri=PageProcessorServer.wsdl
DEBUG - GeronimoLog.debug(66) | getImportInputSource: parent=demo_testje.wsdl, imprt=KwaliteitshandboekWebservice.wsdl, uri=KwaliteitshandboekWebservice.wsdl
16:56:34,237 DEBUG [WSDLLocatorImpl] getImportInputSource: parent=demo_testje.wsdl, imprt=KwaliteitshandboekWebservice.wsdl, uri=KwaliteitshandboekWebservice.wsdl
DEBUG - GeronimoLog.debug(66) | getImportInputSource: parent=demo_testje.wsdl, imprt=TaskManagerWebservice.wsdl, uri=TaskManagerWebservice.wsdl
16:56:34,267 DEBUG [WSDLLocatorImpl] getImportInputSource: parent=demo_testje.wsdl, imprt=TaskManagerWebservice.wsdl, uri=TaskManagerWebservice.wsdl
DEBUG - GeronimoLog.debug(66) | addDefinition(http://demo_testje.wsdl from demo_testje.wsdl)
16:56:34,297 DEBUG [DocumentRegistry] addDefinition(http://demo_testje.wsdl from demo_testje.wsdl)
DEBUG - GeronimoLog.debug(66) | addDefinition(urn:KwaliteitshandboekWebservice from KwaliteitshandboekWebservice.wsdl)
16:56:34,317 DEBUG [DocumentRegistry] addDefinition(urn:KwaliteitshandboekWebservice from KwaliteitshandboekWebservice.wsdl)
DEBUG - GeronimoLog.debug(66) | addDefinition(urn:PageProcessorServer from PageProcessorServer.wsdl)
16:56:34,347 DEBUG [DocumentRegistry] addDefinition(urn:PageProcessorServer from PageProcessorServer.wsdl)
DEBUG - GeronimoLog.debug(66) | addDefinition(urn:TaskManagerWebservice from TaskManagerWebservice.wsdl)
16:56:34,357 DEBUG [DocumentRegistry] addDefinition(urn:TaskManagerWebservice from TaskManagerWebservice.wsdl)
DEBUG - GeronimoLog.debug(66) | Destroying service {http://demo_testje.wsdl}demo_testjeService port demo_testjePort
16:56:34,367 DEBUG [ODEServer] Destroying service {http://demo_testje.wsdl}demo_testjeService port demo_testjePort
DEBUG - GeronimoLog.debug(66) | Couldn't find service {http://demo_testje.wsdl}demo_testjeService port demo_testjePort to destroy.
16:56:34,367 DEBUG [ODEServer] Couldn't find service {http://demo_testje.wsdl}demo_testjeService port demo_testjePort to destroy.
DEBUG - GeronimoLog.debug(66) | Create AxisService: service={http://demo_testje.wsdl}demo_testjeService port=demo_testjePort WSDL=demo_testje.wsdl BPEL=demo_testje.bpel
16:56:34,377 DEBUG [ODEAxisService] Create AxisService: service={http://demo_testje.wsdl}demo_testjeService port=demo_testjePort WSDL=demo_testje.wsdl BPEL=demo_testje.bpel
DEBUG - GeronimoLog.debug(66) | Get import:  import=PageProcessorServer.wsdl parent=file:/C:/apache-tomcat-5.5.20-ode-2007-06-20/webapps/ode/WEB-INF/processes/demo_testje-8/demo_testje.wsdl
16:56:34,387 DEBUG [Axis2WSDLLocator] Get import:  import=PageProcessorServer.wsdl parent=file:/C:/apache-tomcat-5.5.20-ode-2007-06-20/webapps/ode/WEB-INF/processes/demo_testje-8/demo_testje.wsdl
DEBUG - GeronimoLog.debug(66) | Get import:  import=KwaliteitshandboekWebservice.wsdl parent=file:/C:/apache-tomcat-5.5.20-ode-2007-06-20/webapps/ode/WEB-INF/processes/demo_testje-8/demo_testje.wsdl
16:56:34,417 DEBUG [Axis2WSDLLocator] Get import:  import=KwaliteitshandboekWebservice.wsdl parent=file:/C:/apache-tomcat-5.5.20-ode-2007-06-20/webapps/ode/WEB-INF/processes/demo_testje-8/demo_testje.wsdl
DEBUG - GeronimoLog.debug(66) | Get import:  import=TaskManagerWebservice.wsdl parent=file:/C:/apache-tomcat-5.5.20-ode-2007-06-20/webapps/ode/WEB-INF/processes/demo_testje-8/demo_testje.wsdl
16:56:34,427 DEBUG [Axis2WSDLLocator] Get import:  import=TaskManagerWebservice.wsdl parent=file:/C:/apache-tomcat-5.5.20-ode-2007-06-20/webapps/ode/WEB-INF/processes/demo_testje-8/demo_testje.wsdl
DEBUG - GeronimoLog.debug(66) | Constructed a new WSAEndpoint: <?xml version="1.0" encoding="UTF-8"?>
<EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceName xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="demo_testjePort" xmlns:servicens="http://demo_testje.wsdl">servicens:demo_testjeService</ServiceName></Metadata><
Address>http://winterswijk:8080/ode/processes/demo_testje</Address></EndpointReference>
16:56:34,497 DEBUG [WSAEndpoint] Constructed a new WSAEndpoint: <?xml version="1.0" encoding="UTF-8"?>
<EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceName xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="demo_testjePort" xmlns:servicens="http://demo_testje.wsdl">servicens:demo_testjeService</ServiceName></Metadata><
Address>http://winterswijk:8080/ode/processes/demo_testje</Address></EndpointReference>
DEBUG - GeronimoLog.debug(66) | Setting service with empty namespace replication
16:56:34,557 DEBUG [ODEServer] Setting service with empty namespace replication
DEBUG - GeronimoLog.debug(66) | Created Axis2 service {http://demo_testje.wsdl}demo_testjeService
16:56:34,567 DEBUG [ODEServer] Created Axis2 service {http://demo_testje.wsdl}demo_testjeService
DEBUG - GeronimoLog.debug(66) | Activated {http://demo_testje}demo_testje-8 myrole demo_testjeLink: EPR is org.apache.ode.bpel.epr.WSAEndpoint@1cea096
16:56:34,567 DEBUG [BpelProcess] Activated {http://demo_testje}demo_testje-8 myrole demo_testjeLink: EPR is org.apache.ode.bpel.epr.WSAEndpoint@1cea096
DEBUG - GeronimoLog.debug(66) | Activated {http://demo_testje}demo_testje-8
16:56:34,577 DEBUG [BpelProcess] Activated {http://demo_testje}demo_testje-8
DEBUG - GeronimoLog.debug(66) | Rehydrating process {http://demo_testje}demo_testje-8
16:56:34,577 DEBUG [BpelProcess] Rehydrating process {http://demo_testje}demo_testje-8
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:34,837 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:34,857 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:34,867 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:34,877 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:34,887 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:34,897 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:34,907 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:34,917 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:34,967 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:34,998 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,008 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,028 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,038 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,048 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,058 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,068 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,078 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,088 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,098 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,108 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,118 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,128 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,138 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,148 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,158 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,168 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,178 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,648 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,658 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,678 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,688 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,698 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,708 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,718 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,728 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,738 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,738 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,748 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,769 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,769 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,779 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,799 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,809 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,819 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,829 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,839 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,849 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,859 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,869 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,879 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,889 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,909 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,919 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,929 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,939 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,949 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,959 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,969 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,979 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,989 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:35,999 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:36,009 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:36,019 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:36,029 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:36,059 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:36,069 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:36,079 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:36,089 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:36,089 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:36,099 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:36,119 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:36,129 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:36,129 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =ht
tp://schemas.xmlsoap.org/ws/2004/03/business-process/}
16:56:36,139 DEBUG [NSContext] readExternal: contents={khws=urn:KwaliteitshandboekWebservice, xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://demo_testje.wsdl, =htt
p://schemas.xmlsoap.org/ws/2004/03/business-process/}
DEBUG - GeronimoLog.debug(66) | Processing <invoke> element for process {http://demo_testje}demo_testje-8: partnerlink taskManagerWebserviceLink --> {urn:TaskManagerWebservice}TaskManagerWebserviceService:TaskManagerWebservicePort
16:56:36,209 DEBUG [BpelProcess] Processing <invoke> element for process {http://demo_testje}demo_testje-8: partnerlink taskManagerWebserviceLink --> {urn:TaskManagerWebservice}TaskManagerWebserviceService:TaskManagerWebservicePort
DEBUG - GeronimoLog.debug(66) | Processing <invoke> element for process {http://demo_testje}demo_testje-8: partnerlink processServerLink --> {urn:PageProcessorServer}ProcessServerService:ProcessServerPort
16:56:36,209 DEBUG [BpelProcess] Processing <invoke> element for process {http://demo_testje}demo_testje-8: partnerlink processServerLink --> {urn:PageProcessorServer}ProcessServerService:ProcessServerPort
DEBUG - GeronimoLog.debug(66) | Processing <invoke> element for process {http://demo_testje}demo_testje-8: partnerlink kwaliteitshandboekWebserviceLink --> {urn:KwaliteitshandboekWebservice}KwaliteitshandboekWebserviceService:KwaliteitshandboekWebservicePort
16:56:36,209 DEBUG [BpelProcess] Processing <invoke> element for process {http://demo_testje}demo_testje-8: partnerlink kwaliteitshandboekWebserviceLink --> {urn:KwaliteitshandboekWebservice}KwaliteitshandboekWebserviceService:KwaliteitshandboekWebservicePort
DEBUG - GeronimoLog.debug(66) | Setting external service with empty namespace replication
16:56:36,249 DEBUG [ODEServer] Setting external service with empty namespace replication
DEBUG - GeronimoLog.debug(66) | Created external service {urn:KwaliteitshandboekWebservice}KwaliteitshandboekWebserviceService
16:56:36,259 DEBUG [ODEServer] Created external service {urn:KwaliteitshandboekWebservice}KwaliteitshandboekWebserviceService
DEBUG - GeronimoLog.debug(66) | Constructed a new WSAEndpoint: <?xml version="1.0" encoding="UTF-8"?>
<EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceName xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="KwaliteitshandboekWebservicePort" xmlns:servicens="urn:KwaliteitshandboekWebservice">servicens:KwaliteitshandboekWebserviceService</ServiceName></Met
adata><Address>http://vroomshoop:81/web_service/kwaliteitshandboekwebservice.php</Address></EndpointReference>
16:56:36,259 DEBUG [WSAEndpoint] Constructed a new WSAEndpoint: <?xml version="1.0" encoding="UTF-8"?>
<EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceName xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="KwaliteitshandboekWebservicePort" xmlns:servicens="urn:KwaliteitshandboekWebservice">servicens:KwaliteitshandboekWebserviceService</ServiceName></Met
adata><Address>http://vroomshoop:81/web_service/kwaliteitshandboekwebservice.php</Address></EndpointReference>
DEBUG - GeronimoLog.debug(66) | Activated {http://demo_testje}demo_testje-8 partnerrole kwaliteitshandboekWebserviceLink: EPR is org.apache.ode.bpel.epr.WSAEndpoint@1fecaeb
16:56:36,269 DEBUG [BpelProcess] Activated {http://demo_testje}demo_testje-8 partnerrole kwaliteitshandboekWebserviceLink: EPR is org.apache.ode.bpel.epr.WSAEndpoint@1fecaeb
DEBUG - GeronimoLog.debug(66) | Setting external service with empty namespace replication
16:56:36,279 DEBUG [ODEServer] Setting external service with empty namespace replication
DEBUG - GeronimoLog.debug(66) | Created external service {urn:PageProcessorServer}ProcessServerService
16:56:36,289 DEBUG [ODEServer] Created external service {urn:PageProcessorServer}ProcessServerService
DEBUG - GeronimoLog.debug(66) | Constructed a new WSAEndpoint: <?xml version="1.0" encoding="UTF-8"?>
<EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceName xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="ProcessServerPort" xmlns:servicens="urn:PageProcessorServer">servicens:ProcessServerService</ServiceName></Metadata><Address>http://localhost:81/web_
service/process_server.php</Address></EndpointReference>
16:56:36,289 DEBUG [WSAEndpoint] Constructed a new WSAEndpoint: <?xml version="1.0" encoding="UTF-8"?>
<EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceName xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="ProcessServerPort" xmlns:servicens="urn:PageProcessorServer">servicens:ProcessServerService</ServiceName></Metadata><Address>http://localhost:81/web_
service/process_server.php</Address></EndpointReference>
DEBUG - GeronimoLog.debug(66) | Activated {http://demo_testje}demo_testje-8 partnerrole processServerLink: EPR is org.apache.ode.bpel.epr.WSAEndpoint@bc22eb
16:56:36,299 DEBUG [BpelProcess] Activated {http://demo_testje}demo_testje-8 partnerrole processServerLink: EPR is org.apache.ode.bpel.epr.WSAEndpoint@bc22eb
DEBUG - GeronimoLog.debug(66) | Setting external service with empty namespace replication
16:56:36,299 DEBUG [ODEServer] Setting external service with empty namespace replication
DEBUG - GeronimoLog.debug(66) | Created external service {urn:TaskManagerWebservice}TaskManagerWebserviceService
16:56:36,309 DEBUG [ODEServer] Created external service {urn:TaskManagerWebservice}TaskManagerWebserviceService
DEBUG - GeronimoLog.debug(66) | Constructed a new WSAEndpoint: <?xml version="1.0" encoding="UTF-8"?>
<EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceName xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="TaskManagerWebservicePort" xmlns:servicens="urn:TaskManagerWebservice">servicens:TaskManagerWebserviceService</ServiceName></Metadata><Address>http:/
/vroomshoop:81/web_service/taskmanagerwebservice.php</Address></EndpointReference>
16:56:36,309 DEBUG [WSAEndpoint] Constructed a new WSAEndpoint: <?xml version="1.0" encoding="UTF-8"?>
<EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceName xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="TaskManagerWebservicePort" xmlns:servicens="urn:TaskManagerWebservice">servicens:TaskManagerWebserviceService</ServiceName></Metadata><Address>http:/
/vroomshoop:81/web_service/taskmanagerwebservice.php</Address></EndpointReference>
DEBUG - GeronimoLog.debug(66) | Activated {http://demo_testje}demo_testje-8 partnerrole taskManagerWebserviceLink: EPR is org.apache.ode.bpel.epr.WSAEndpoint@19872ad
16:56:36,319 DEBUG [BpelProcess] Activated {http://demo_testje}demo_testje-8 partnerrole taskManagerWebserviceLink: EPR is org.apache.ode.bpel.epr.WSAEndpoint@19872ad
DEBUG - GeronimoLog.debug(66) | Starting transaction.
16:56:36,329 DEBUG [QuartzSchedulerImpl] Starting transaction.
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:56:36,339 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | close
16:56:36,349 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | Creating process DAO for {http://demo_testje}demo_testje-8 (guid=hqejbhcnphr2e135enami8)
16:56:36,449 DEBUG [BpelServerImpl] Creating process DAO for {http://demo_testje}demo_testje-8 (guid=hqejbhcnphr2e135enami8)
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:56:36,840 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | 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 = ?)
16:56:36,840 DEBUG [sql] 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 = ?)
DEBUG - GeronimoLog.debug(66) | bound (1,{http://demo_testje}demo_testje-8)
16:56:36,850 DEBUG [sql] bound (1,{http://demo_testje}demo_testje-8)
DEBUG - GeronimoLog.debug(66) | close
16:56:36,850 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | Found ProcessDAO for {http://demo_testje}demo_testje-8 with GUID hqejbhcnphr2e135enami8
16:56:36,860 DEBUG [BpelServerImpl] Found ProcessDAO for {http://demo_testje}demo_testje-8 with GUID hqejbhcnphr2e135enami8
DEBUG - GeronimoLog.debug(66) | Commiting transaction.
16:56:36,860 DEBUG [QuartzSchedulerImpl] Commiting transaction.
INFO - GeronimoLog.info(79) | Activated process {http://demo_testje}demo_testje-8.
16:56:36,890 INFO  [BpelServerImpl] Activated process {http://demo_testje}demo_testje-8.
DEBUG - GeronimoLog.debug(66) | firing event: {ProcessStoreEvent#ACTVIATED:{http://OPM_modifyObject}OPM_modifyObject-4}
16:56:36,900 DEBUG [ProcessStoreImpl] firing event: {ProcessStoreEvent#ACTVIATED:{http://OPM_modifyObject}OPM_modifyObject-4}
DEBUG - GeronimoLog.debug(66) | Process store event: {ProcessStoreEvent#ACTVIATED:{http://OPM_modifyObject}OPM_modifyObject-4}
16:56:36,900 DEBUG [ODEServer] Process store event: {ProcessStoreEvent#ACTVIATED:{http://OPM_modifyObject}OPM_modifyObject-4}
DEBUG - GeronimoLog.trace(54) | unregister: {http://OPM_modifyObject}OPM_modifyObject-4
16:56:36,910 DEBUG [BpelServerImpl] unregister: {http://OPM_modifyObject}OPM_modifyObject-4
INFO - GeronimoLog.info(79) | Process {http://OPM_modifyObject}OPM_modifyObject-4 has been unregistered.
16:56:36,910 INFO  [BpelServerImpl] Process {http://OPM_modifyObject}OPM_modifyObject-4 has been unregistered.
DEBUG - GeronimoLog.debug(66) | register: {http://OPM_modifyObject}OPM_modifyObject-4
16:56:36,910 DEBUG [BpelServerImpl] register: {http://OPM_modifyObject}OPM_modifyObject-4
DEBUG - GeronimoLog.debug(66) | Registering process {http://OPM_modifyObject}OPM_modifyObject-4 with server.
16:56:36,920 DEBUG [BpelServerImpl] Registering process {http://OPM_modifyObject}OPM_modifyObject-4 with server.
DEBUG - GeronimoLog.debug(66) | Register process: serviceId={http://OPM_modifyObject.wsdl}OPM_modifyObjectService:OPM_modifyObjectPort, process=BpelProcess[{http://OPM_modifyObject}OPM_modifyObject-4]
16:56:36,930 DEBUG [BpelEngineImpl] Register process: serviceId={http://OPM_modifyObject.wsdl}OPM_modifyObjectService:OPM_modifyObjectPort, process=BpelProcess[{http://OPM_modifyObject}OPM_modifyObject-4]
DEBUG - GeronimoLog.debug(66) | Activating {http://OPM_modifyObject}OPM_modifyObject-4
16:56:36,930 DEBUG [BpelProcess] Activating {http://OPM_modifyObject}OPM_modifyObject-4
DEBUG - GeronimoLog.debug(66) | addDefinition(urn:OPMWebservice from OPMWebservice.wsdl)
16:56:36,960 DEBUG [DocumentRegistry] addDefinition(urn:OPMWebservice from OPMWebservice.wsdl)
DEBUG - GeronimoLog.debug(66) | getImportInputSource: parent=OPM_modifyObject.wsdl, imprt=PageProcessorServer.wsdl, uri=PageProcessorServer.wsdl
16:56:36,980 DEBUG [WSDLLocatorImpl] getImportInputSource: parent=OPM_modifyObject.wsdl, imprt=PageProcessorServer.wsdl, uri=PageProcessorServer.wsdl
DEBUG - GeronimoLog.debug(66) | getImportInputSource: parent=OPM_modifyObject.wsdl, imprt=OPMWebservice.wsdl, uri=OPMWebservice.wsdl
16:56:37,030 DEBUG [WSDLLocatorImpl] getImportInputSource: parent=OPM_modifyObject.wsdl, imprt=OPMWebservice.wsdl, uri=OPMWebservice.wsdl
DEBUG - GeronimoLog.debug(66) | getImportInputSource: parent=OPM_modifyObject.wsdl, imprt=TaskManagerWebservice.wsdl, uri=TaskManagerWebservice.wsdl
16:56:37,040 DEBUG [WSDLLocatorImpl] getImportInputSource: parent=OPM_modifyObject.wsdl, imprt=TaskManagerWebservice.wsdl, uri=TaskManagerWebservice.wsdl
DEBUG - GeronimoLog.debug(66) | addDefinition(http://OPM_modifyObject.wsdl from OPM_modifyObject.wsdl)
16:56:37,060 DEBUG [DocumentRegistry] addDefinition(http://OPM_modifyObject.wsdl from OPM_modifyObject.wsdl)
DEBUG - GeronimoLog.debug(66) | addDefinition(urn:PageProcessorServer from PageProcessorServer.wsdl)
16:56:37,100 DEBUG [DocumentRegistry] addDefinition(urn:PageProcessorServer from PageProcessorServer.wsdl)
DEBUG - GeronimoLog.debug(66) | addDefinition(urn:TaskManagerWebservice from TaskManagerWebservice.wsdl)
16:56:37,110 DEBUG [DocumentRegistry] addDefinition(urn:TaskManagerWebservice from TaskManagerWebservice.wsdl)
DEBUG - GeronimoLog.debug(66) | Destroying service {http://OPM_modifyObject.wsdl}OPM_modifyObjectService port OPM_modifyObjectPort
16:56:37,110 DEBUG [ODEServer] Destroying service {http://OPM_modifyObject.wsdl}OPM_modifyObjectService port OPM_modifyObjectPort
DEBUG - GeronimoLog.debug(66) | Couldn't find service {http://OPM_modifyObject.wsdl}OPM_modifyObjectService port OPM_modifyObjectPort to destroy.
16:56:37,120 DEBUG [ODEServer] Couldn't find service {http://OPM_modifyObject.wsdl}OPM_modifyObjectService port OPM_modifyObjectPort to destroy.
DEBUG - GeronimoLog.debug(66) | Create AxisService: service={http://OPM_modifyObject.wsdl}OPM_modifyObjectService port=OPM_modifyObjectPort WSDL=OPM_modifyObject.wsdl BPEL=OPM_modifyObject.bpel
16:56:37,120 DEBUG [ODEAxisService] Create AxisService: service={http://OPM_modifyObject.wsdl}OPM_modifyObjectService port=OPM_modifyObjectPort WSDL=OPM_modifyObject.wsdl BPEL=OPM_modifyObject.bpel
DEBUG - GeronimoLog.debug(66) | Get import:  import=PageProcessorServer.wsdl parent=file:/C:/apache-tomcat-5.5.20-ode-1.0/webapps/ode/WEB-INF/processes/OPM_modifyObject/OPM_modifyObject.wsdl
16:56:37,140 DEBUG [Axis2WSDLLocator] Get import:  import=PageProcessorServer.wsdl parent=file:/C:/apache-tomcat-5.5.20-ode-1.0/webapps/ode/WEB-INF/processes/OPM_modifyObject/OPM_modifyObject.wsdl
DEBUG - GeronimoLog.debug(66) | Get import:  import=OPMWebservice.wsdl parent=file:/C:/apache-tomcat-5.5.20-ode-1.0/webapps/ode/WEB-INF/processes/OPM_modifyObject/OPM_modifyObject.wsdl
16:56:37,160 DEBUG [Axis2WSDLLocator] Get import:  import=OPMWebservice.wsdl parent=file:/C:/apache-tomcat-5.5.20-ode-1.0/webapps/ode/WEB-INF/processes/OPM_modifyObject/OPM_modifyObject.wsdl
DEBUG - GeronimoLog.debug(66) | Get import:  import=TaskManagerWebservice.wsdl parent=file:/C:/apache-tomcat-5.5.20-ode-1.0/webapps/ode/WEB-INF/processes/OPM_modifyObject/OPM_modifyObject.wsdl
16:56:37,170 DEBUG [Axis2WSDLLocator] Get import:  import=TaskManagerWebservice.wsdl parent=file:/C:/apache-tomcat-5.5.20-ode-1.0/webapps/ode/WEB-INF/processes/OPM_modifyObject/OPM_modifyObject.wsdl
DEBUG - GeronimoLog.debug(66) | Constructed a new WSAEndpoint: <?xml version="1.0" encoding="UTF-8"?>
<EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceName xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="OPM_modifyObjectPort" xmlns:servicens="http://OPM_modifyObject.wsdl">servicens:OPM_modifyObjectService</ServiceName></Metadata><Address>http://winter
swijk:8080/ode/processes/OPM_modifyObject</Address></EndpointReference>
16:56:37,190 DEBUG [WSAEndpoint] Constructed a new WSAEndpoint: <?xml version="1.0" encoding="UTF-8"?>
<EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceName xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="OPM_modifyObjectPort" xmlns:servicens="http://OPM_modifyObject.wsdl">servicens:OPM_modifyObjectService</ServiceName></Metadata><Address>http://winter
swijk:8080/ode/processes/OPM_modifyObject</Address></EndpointReference>
DEBUG - GeronimoLog.debug(66) | Setting service with empty namespace replication
16:56:37,210 DEBUG [ODEServer] Setting service with empty namespace replication
DEBUG - GeronimoLog.debug(66) | Created Axis2 service {http://OPM_modifyObject.wsdl}OPM_modifyObjectService
16:56:37,210 DEBUG [ODEServer] Created Axis2 service {http://OPM_modifyObject.wsdl}OPM_modifyObjectService
DEBUG - GeronimoLog.debug(66) | Activated {http://OPM_modifyObject}OPM_modifyObject-4 myrole OPM_modifyObjectLink: EPR is org.apache.ode.bpel.epr.WSAEndpoint@b101cf
16:56:37,220 DEBUG [BpelProcess] Activated {http://OPM_modifyObject}OPM_modifyObject-4 myrole OPM_modifyObjectLink: EPR is org.apache.ode.bpel.epr.WSAEndpoint@b101cf
DEBUG - GeronimoLog.debug(66) | Activated {http://OPM_modifyObject}OPM_modifyObject-4
16:56:37,220 DEBUG [BpelProcess] Activated {http://OPM_modifyObject}OPM_modifyObject-4
DEBUG - GeronimoLog.debug(66) | Rehydrating process {http://OPM_modifyObject}OPM_modifyObject-4
16:56:37,220 DEBUG [BpelProcess] Rehydrating process {http://OPM_modifyObject}OPM_modifyObject-4
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:37,270 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:37,290 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:37,300 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:37,311 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:37,321 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:37,361 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:37,361 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:37,371 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:37,791 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:37,791 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:37,801 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:37,831 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:37,831 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:37,841 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:37,851 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,001 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,001 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,021 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,041 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,051 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,051 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,071 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,092 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,122 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,132 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,142 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,152 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,162 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,162 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,202 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,212 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,222 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,222 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,232 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,252 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,262 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,272 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.trace(54) | readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.
org/ws/2004/03/business-process/}
16:56:38,282 DEBUG [NSContext] readExternal: contents={xsd=http://www.w3.org/2001/XMLSchema, bpel=http://schemas.xmlsoap.org/ws/2004/03/business-process/, opmws=urn:OPMWebservice, pps=urn:PageProcessorServer, tmws=urn:TaskManagerWebservice, lns=http://OPM_modifyObject.wsdl, =http://schemas.xmlsoap.o
rg/ws/2004/03/business-process/}
DEBUG - GeronimoLog.debug(66) | Processing <invoke> element for process {http://OPM_modifyObject}OPM_modifyObject-4: partnerlink OPMWebserviceLink --> {urn:OPMWebservice}OPMWebserviceService:OPMWebservicePort
16:56:38,322 DEBUG [BpelProcess] Processing <invoke> element for process {http://OPM_modifyObject}OPM_modifyObject-4: partnerlink OPMWebserviceLink --> {urn:OPMWebservice}OPMWebserviceService:OPMWebservicePort
DEBUG - GeronimoLog.debug(66) | Processing <invoke> element for process {http://OPM_modifyObject}OPM_modifyObject-4: partnerlink taskManagerWebserviceLink --> {urn:TaskManagerWebservice}TaskManagerWebserviceService:TaskManagerWebservicePort
16:56:38,332 DEBUG [BpelProcess] Processing <invoke> element for process {http://OPM_modifyObject}OPM_modifyObject-4: partnerlink taskManagerWebserviceLink --> {urn:TaskManagerWebservice}TaskManagerWebserviceService:TaskManagerWebservicePort
DEBUG - GeronimoLog.debug(66) | Processing <invoke> element for process {http://OPM_modifyObject}OPM_modifyObject-4: partnerlink processServerLink --> {urn:PageProcessorServer}ProcessServerService:ProcessServerPort
16:56:38,332 DEBUG [BpelProcess] Processing <invoke> element for process {http://OPM_modifyObject}OPM_modifyObject-4: partnerlink processServerLink --> {urn:PageProcessorServer}ProcessServerService:ProcessServerPort
DEBUG - GeronimoLog.debug(66) | Setting external service with empty namespace replication
16:56:38,342 DEBUG [ODEServer] Setting external service with empty namespace replication
DEBUG - GeronimoLog.debug(66) | Created external service {urn:OPMWebservice}OPMWebserviceService
16:56:38,342 DEBUG [ODEServer] Created external service {urn:OPMWebservice}OPMWebserviceService
DEBUG - GeronimoLog.debug(66) | Constructed a new WSAEndpoint: <?xml version="1.0" encoding="UTF-8"?>
<EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceName xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="OPMWebservicePort" xmlns:servicens="urn:OPMWebservice">servicens:OPMWebserviceService</ServiceName></Metadata><Address>http://vroomshoop:81/web_servi
ce/OPMWebservice.php</Address></EndpointReference>
16:56:38,352 DEBUG [WSAEndpoint] Constructed a new WSAEndpoint: <?xml version="1.0" encoding="UTF-8"?>
<EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceName xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="OPMWebservicePort" xmlns:servicens="urn:OPMWebservice">servicens:OPMWebserviceService</ServiceName></Metadata><Address>http://vroomshoop:81/web_servi
ce/OPMWebservice.php</Address></EndpointReference>
DEBUG - GeronimoLog.debug(66) | Activated {http://OPM_modifyObject}OPM_modifyObject-4 partnerrole OPMWebserviceLink: EPR is org.apache.ode.bpel.epr.WSAEndpoint@b1be82
16:56:38,352 DEBUG [BpelProcess] Activated {http://OPM_modifyObject}OPM_modifyObject-4 partnerrole OPMWebserviceLink: EPR is org.apache.ode.bpel.epr.WSAEndpoint@b1be82
DEBUG - GeronimoLog.debug(66) | Setting external service with empty namespace replication
16:56:38,362 DEBUG [ODEServer] Setting external service with empty namespace replication
DEBUG - GeronimoLog.debug(66) | Created external service {urn:TaskManagerWebservice}TaskManagerWebserviceService
16:56:38,362 DEBUG [ODEServer] Created external service {urn:TaskManagerWebservice}TaskManagerWebserviceService
DEBUG - GeronimoLog.debug(66) | Constructed a new WSAEndpoint: <?xml version="1.0" encoding="UTF-8"?>
<EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceName xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="TaskManagerWebservicePort" xmlns:servicens="urn:TaskManagerWebservice">servicens:TaskManagerWebserviceService</ServiceName></Metadata><Address>http:/
/vroomshoop:81/web_service/taskmanagerwebservice.php</Address></EndpointReference>
16:56:38,372 DEBUG [WSAEndpoint] Constructed a new WSAEndpoint: <?xml version="1.0" encoding="UTF-8"?>
<EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceName xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="TaskManagerWebservicePort" xmlns:servicens="urn:TaskManagerWebservice">servicens:TaskManagerWebserviceService</ServiceName></Metadata><Address>http:/
/vroomshoop:81/web_service/taskmanagerwebservice.php</Address></EndpointReference>
DEBUG - GeronimoLog.debug(66) | Activated {http://OPM_modifyObject}OPM_modifyObject-4 partnerrole taskManagerWebserviceLink: EPR is org.apache.ode.bpel.epr.WSAEndpoint@15264db
16:56:38,382 DEBUG [BpelProcess] Activated {http://OPM_modifyObject}OPM_modifyObject-4 partnerrole taskManagerWebserviceLink: EPR is org.apache.ode.bpel.epr.WSAEndpoint@15264db
DEBUG - GeronimoLog.debug(66) | Setting external service with empty namespace replication
16:56:38,392 DEBUG [ODEServer] Setting external service with empty namespace replication
DEBUG - GeronimoLog.debug(66) | Created external service {urn:PageProcessorServer}ProcessServerService
16:56:38,392 DEBUG [ODEServer] Created external service {urn:PageProcessorServer}ProcessServerService
DEBUG - GeronimoLog.debug(66) | Constructed a new WSAEndpoint: <?xml version="1.0" encoding="UTF-8"?>
<EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceName xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="ProcessServerPort" xmlns:servicens="urn:PageProcessorServer">servicens:ProcessServerService</ServiceName></Metadata><Address>http://localhost:81/web_
service/process_server.php</Address></EndpointReference>
16:56:38,402 DEBUG [WSAEndpoint] Constructed a new WSAEndpoint: <?xml version="1.0" encoding="UTF-8"?>
<EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceName xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="ProcessServerPort" xmlns:servicens="urn:PageProcessorServer">servicens:ProcessServerService</ServiceName></Metadata><Address>http://localhost:81/web_
service/process_server.php</Address></EndpointReference>
DEBUG - GeronimoLog.debug(66) | Activated {http://OPM_modifyObject}OPM_modifyObject-4 partnerrole processServerLink: EPR is org.apache.ode.bpel.epr.WSAEndpoint@1174031
16:56:38,412 DEBUG [BpelProcess] Activated {http://OPM_modifyObject}OPM_modifyObject-4 partnerrole processServerLink: EPR is org.apache.ode.bpel.epr.WSAEndpoint@1174031
DEBUG - GeronimoLog.debug(66) | Starting transaction.
16:56:38,412 DEBUG [QuartzSchedulerImpl] Starting transaction.
DEBUG - GeronimoLog.debug(66) | Creating process DAO for {http://OPM_modifyObject}OPM_modifyObject-4 (guid=hqejbhcnphr2cxfe0gxg1q)
16:56:38,412 DEBUG [BpelServerImpl] Creating process DAO for {http://OPM_modifyObject}OPM_modifyObject-4 (guid=hqejbhcnphr2cxfe0gxg1q)
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:56:38,422 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | 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 = ?)
16:56:38,432 DEBUG [sql] 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 = ?)
DEBUG - GeronimoLog.debug(66) | bound (1,{http://OPM_modifyObject}OPM_modifyObject-4)
16:56:38,432 DEBUG [sql] bound (1,{http://OPM_modifyObject}OPM_modifyObject-4)
DEBUG - GeronimoLog.debug(66) | close
16:56:38,442 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | Found ProcessDAO for {http://OPM_modifyObject}OPM_modifyObject-4 with GUID hqejbhcnphr2cxfe0gxg1q
16:56:38,442 DEBUG [BpelServerImpl] Found ProcessDAO for {http://OPM_modifyObject}OPM_modifyObject-4 with GUID hqejbhcnphr2cxfe0gxg1q
DEBUG - GeronimoLog.debug(66) | Commiting transaction.
16:56:38,452 DEBUG [QuartzSchedulerImpl] Commiting transaction.
INFO - GeronimoLog.info(79) | Activated process {http://OPM_modifyObject}OPM_modifyObject-4.
16:56:38,452 INFO  [BpelServerImpl] Activated process {http://OPM_modifyObject}OPM_modifyObject-4.
DEBUG - GeronimoLog.debug(66) | Initializing JCA adapter.
16:56:38,452 DEBUG [ODEServer] Initializing JCA adapter.
DEBUG - GeronimoLog.debug(66) | Created registry on port 2099
16:56:38,772 DEBUG [RmiTransportServerImpl] Created registry on port 2099
DEBUG - GeronimoLog.debug(66) | Bound JCA server as "jcaServer" on registry port 2099
16:56:38,873 DEBUG [RmiTransportServerImpl] Bound JCA server as "jcaServer" on registry port 2099
INFO - GeronimoLog.info(79) | Poller started.
16:56:38,873 INFO  [DeploymentPoller] Poller started.
INFO - GeronimoLog.info(79) | Process deployment polling started on path C:\apache-tomcat-5.5.20-ode-2007-06-20\webapps\ode\WEB-INF\processes.
16:56:38,883 INFO  [ODEServer] Process deployment polling started on path C:\apache-tomcat-5.5.20-ode-2007-06-20\webapps\ode\WEB-INF\processes.
INFO - GeronimoLog.info(79) | ODE Service Engine has been started.
16:56:38,883 INFO  [ODEServer] ODE Service Engine has been started.
29-jun-2007 16:56:40 org.apache.coyote.http11.Http11BaseProtocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
29-jun-2007 16:56:41 org.apache.jk.common.ChannelSocket init
INFO: JK: ajp13 listening on /0.0.0.0:8009
29-jun-2007 16:56:41 org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=0/70  config=null
29-jun-2007 16:56:41 org.apache.catalina.storeconfig.StoreLoader load
INFO: Find registry server-registry.xml at classpath resource
29-jun-2007 16:56:41 org.apache.catalina.startup.Catalina start
INFO: Server startup in 28167 ms
DEBUG - GeronimoLog.debug(66) | getNonManagedTXConnection()
16:56:54,142 DEBUG [JobStoreJTA] getNonManagedTXConnection()
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:56:54,142 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | Setting isolation level to 2
16:56:54,142 DEBUG [sql] Setting isolation level to 2
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is desired by: ODE_QuartzSchedulerThread
16:56:54,152 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is desired by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is being obtained: ODE_QuartzSchedulerThread
16:56:54,152 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is being obtained: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' given to: ODE_QuartzSchedulerThread
16:56:54,162 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' given to: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is desired by: ODE_QuartzSchedulerThread
16:56:54,162 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is desired by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is being obtained: ODE_QuartzSchedulerThread
16:56:54,162 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is being obtained: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' given to: ODE_QuartzSchedulerThread
16:56:54,172 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' given to: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
16:56:54,172 DEBUG [JobStoreJTA] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
16:56:54,172 DEBUG [sql] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
DEBUG - GeronimoLog.debug(66) | bound (2,WAITING) (4,1183128954172) (1,MISFIRED) (3,WAITING)
16:56:54,182 DEBUG [JobStoreJTA] bound (2,WAITING) (4,1183128954172) (1,MISFIRED) (3,WAITING)
DEBUG - GeronimoLog.debug(66) | bound (2,WAITING) (4,1183128954172) (1,MISFIRED) (3,WAITING)
16:56:54,182 DEBUG [sql] bound (2,WAITING) (4,1183128954172) (1,MISFIRED) (3,WAITING)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
16:56:54,182 DEBUG [JobStoreJTA] prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
16:56:54,192 DEBUG [sql] prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
DEBUG - GeronimoLog.debug(66) | bound (1,WAITING)
16:56:54,192 DEBUG [JobStoreJTA] bound (1,WAITING)
DEBUG - GeronimoLog.debug(66) | bound (1,WAITING)
16:56:54,192 DEBUG [sql] bound (1,WAITING)
DEBUG - GeronimoLog.debug(66) | COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@114a3c6
16:56:54,202 DEBUG [JobStoreJTA] COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@114a3c6
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' retuned by: ODE_QuartzSchedulerThread
16:56:54,202 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' retuned by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' retuned by: ODE_QuartzSchedulerThread
16:56:54,212 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' retuned by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | close
16:56:54,222 DEBUG [JobStoreJTA] close
DEBUG - GeronimoLog.debug(66) | close
16:56:54,222 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | getNonManagedTXConnection()
16:57:23,550 DEBUG [JobStoreJTA] getNonManagedTXConnection()
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:57:23,560 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | Setting isolation level to 2
16:57:23,560 DEBUG [sql] Setting isolation level to 2
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is desired by: ODE_QuartzSchedulerThread
16:57:23,560 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is desired by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is being obtained: ODE_QuartzSchedulerThread
16:57:23,570 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is being obtained: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' given to: ODE_QuartzSchedulerThread
16:57:23,570 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' given to: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is desired by: ODE_QuartzSchedulerThread
16:57:23,570 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is desired by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is being obtained: ODE_QuartzSchedulerThread
16:57:23,580 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is being obtained: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' given to: ODE_QuartzSchedulerThread
16:57:23,580 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' given to: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
16:57:23,580 DEBUG [JobStoreJTA] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
16:57:23,590 DEBUG [sql] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
DEBUG - GeronimoLog.debug(66) | bound (2,WAITING) (4,1183128983580) (1,MISFIRED) (3,WAITING)
16:57:23,590 DEBUG [JobStoreJTA] bound (2,WAITING) (4,1183128983580) (1,MISFIRED) (3,WAITING)
DEBUG - GeronimoLog.debug(66) | bound (2,WAITING) (4,1183128983580) (1,MISFIRED) (3,WAITING)
16:57:23,590 DEBUG [sql] bound (2,WAITING) (4,1183128983580) (1,MISFIRED) (3,WAITING)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
16:57:23,600 DEBUG [JobStoreJTA] prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
16:57:23,600 DEBUG [sql] prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
DEBUG - GeronimoLog.debug(66) | bound (1,WAITING)
16:57:23,610 DEBUG [JobStoreJTA] bound (1,WAITING)
DEBUG - GeronimoLog.debug(66) | bound (1,WAITING)
16:57:23,610 DEBUG [sql] bound (1,WAITING)
DEBUG - GeronimoLog.debug(66) | COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@e674d6
16:57:23,610 DEBUG [JobStoreJTA] COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@e674d6
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' retuned by: ODE_QuartzSchedulerThread
16:57:23,620 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' retuned by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' retuned by: ODE_QuartzSchedulerThread
16:57:23,620 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' retuned by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | close
16:57:23,630 DEBUG [JobStoreJTA] close
DEBUG - GeronimoLog.debug(66) | close
16:57:23,630 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | MisfireHandler: scanning for misfires...
16:57:26,834 DEBUG [JobStoreJTA] MisfireHandler: scanning for misfires...
DEBUG - GeronimoLog.debug(66) | doRecoverMisfires() callled
16:57:26,834 DEBUG [JobStoreJTA] doRecoverMisfires() callled
DEBUG - GeronimoLog.debug(66) | getNonManagedTXConnection()
16:57:26,834 DEBUG [JobStoreJTA] getNonManagedTXConnection()
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:57:26,844 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | Setting isolation level to 2
16:57:26,844 DEBUG [sql] Setting isolation level to 2
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is desired by: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
16:57:26,854 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is desired by: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is being obtained: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
16:57:26,854 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is being obtained: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' given to: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
16:57:26,854 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' given to: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
16:57:26,864 DEBUG [JobStoreJTA] prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
16:57:26,864 DEBUG [sql] prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
DEBUG - GeronimoLog.debug(66) | bound (1,MISFIRED)
16:57:26,864 DEBUG [JobStoreJTA] bound (1,MISFIRED)
DEBUG - GeronimoLog.debug(66) | bound (1,MISFIRED)
16:57:26,874 DEBUG [sql] bound (1,MISFIRED)
DEBUG - GeronimoLog.debug(66) | Found 0 triggers that missed their scheduled fire-time.
16:57:26,874 DEBUG [JobStoreJTA] Found 0 triggers that missed their scheduled fire-time.
DEBUG - GeronimoLog.debug(66) | COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@bdb6ae
16:57:26,874 DEBUG [JobStoreJTA] COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@bdb6ae
DEBUG - GeronimoLog.debug(66) | doRecoverMisfires() returned moreToDo = false
16:57:26,884 DEBUG [JobStoreJTA] doRecoverMisfires() returned moreToDo = false
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' retuned by: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
16:57:26,884 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' retuned by: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
DEBUG - GeronimoLog.debug(66) | close
16:57:26,884 DEBUG [JobStoreJTA] close
DEBUG - GeronimoLog.debug(66) | close
16:57:26,894 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | Checking for Operation using WSAAction : urn:anonOutInOp
16:57:43,446 DEBUG [ODEAxisDispatcher] Checking for Operation using WSAAction : urn:anonOutInOp
DEBUG - GeronimoLog.debug(66) | Checking for Operation using SOAP message body's first child's local name : initiate
16:57:43,446 DEBUG [ODEAxisDispatcher] Checking for Operation using SOAP message body's first child's local name : initiate
DEBUG - GeronimoLog.debug(66) | Found operation org.apache.axis2.description.InOutAxisOperation@1fce2f2
16:57:43,446 DEBUG [ODEAxisDispatcher] Found operation org.apache.axis2.description.InOutAxisOperation@1fce2f2
DEBUG - GeronimoLog.debug(66) | Received request message for demo_testje.initiate
16:57:43,456 DEBUG [ODEMessageReceiver] Received request message for demo_testje.initiate
DEBUG - GeronimoLog.debug(66) | Starting transaction.
16:57:43,466 DEBUG [ODEService] Starting transaction.
DEBUG - GeronimoLog.debug(66) | Routed: svcQname {http://demo_testje.wsdl}demo_testjeService --> BpelProcess[{http://demo_testje}demo_testje-8]
16:57:43,486 DEBUG [BpelEngineImpl] Routed: svcQname {http://demo_testje.wsdl}demo_testjeService --> BpelProcess[{http://demo_testje}demo_testje-8]
DEBUG - GeronimoLog.trace(54) | Mex[hqejbhcnphr2e149g9im9r].setPortOp(PortType: name={http://demo_testje.wsdl}demo_testjePortType
Operation: name=initiate
style=REQUEST_RESPONSE,1
Input: name=null
Message: name={http://demo_testje.wsdl}initiateRequest
Part: name=processInstanceID
typeName={http://www.w3.org/2001/XMLSchema}string
Output: name=null
Message: name={http://demo_testje.wsdl}userReplyMessage
Part: name=return
typeName={http://www.w3.org/2001/XMLSchema}string
Operation: name=taskCompleted2
style=REQUEST_RESPONSE,1
Input: name=null
Message: name={http://demo_testje.wsdl}taskCompletedRequest
Part: name=userID
typeName={http://www.w3.org/2001/XMLSchema}int
Part: name=chosenCompletionValue
typeName={http://www.w3.org/2001/XMLSchema}string
Part: name=processInstanceID
typeName={http://www.w3.org/2001/XMLSchema}string
Part: name=feedback
typeName={http://www.w3.org/2001/XMLSchema}string
Output: name=null
Message: name={http://demo_testje.wsdl}userReplyMessage
Part: name=return
typeName={http://www.w3.org/2001/XMLSchema}string
Operation: name=taskCompleted3
style=REQUEST_RESPONSE,1
Input: name=null
Message: name={http://demo_testje.wsdl}taskCompletedRequest
Part: name=userID
typeName={http://www.w3.org/2001/XMLSchema}int
Part: name=chosenCompletionValue
typeName={http://www.w3.org/2001/XMLSchema}string
Part: name=processInstanceID
typeName={http://www.w3.org/2001/XMLSchema}string
Part: name=feedback
typeName={http://www.w3.org/2001/XMLSchema}string
Output: name=null
Message: name={http://demo_testje.wsdl}userReplyMessage
Part: name=return
typeName={http://www.w3.org/2001/XMLSchema}string
Operation: name=taskCompleted6
style=REQUEST_RESPONSE,1
Input: name=null
Message: name={http://demo_testje.wsdl}taskCompletedRequest
Part: name=userID
typeName={http://www.w3.org/2001/XMLSchema}int
Part: name=chosenCompletionValue
typeName={http://www.w3.org/2001/XMLSchema}string
Part: name=processInstanceID
typeName={http://www.w3.org/2001/XMLSchema}string
Part: name=feedback
typeName={http://www.w3.org/2001/XMLSchema}string
Output: name=null
Message: name={http://demo_testje.wsdl}userReplyMessage
Part: name=return
typeName={http://www.w3.org/2001/XMLSchema}string,Operation: name=initiate
style=REQUEST_RESPONSE,1
Input: name=null
Message: name={http://demo_testje.wsdl}initiateRequest
Part: name=processInstanceID
typeName={http://www.w3.org/2001/XMLSchema}string
Output: name=null
Message: name={http://demo_testje.wsdl}userReplyMessage
Part: name=return
typeName={http://www.w3.org/2001/XMLSchema}string)
16:57:43,536 DEBUG [MessageExchangeImpl] Mex[hqejbhcnphr2e149g9im9r].setPortOp(PortType: name={http://demo_testje.wsdl}demo_testjePortType
Operation: name=initiate
style=REQUEST_RESPONSE,1
Input: name=null
Message: name={http://demo_testje.wsdl}initiateRequest
Part: name=processInstanceID
typeName={http://www.w3.org/2001/XMLSchema}string
Output: name=null
Message: name={http://demo_testje.wsdl}userReplyMessage
Part: name=return
typeName={http://www.w3.org/2001/XMLSchema}string
Operation: name=taskCompleted2
style=REQUEST_RESPONSE,1
Input: name=null
Message: name={http://demo_testje.wsdl}taskCompletedRequest
Part: name=userID
typeName={http://www.w3.org/2001/XMLSchema}int
Part: name=chosenCompletionValue
typeName={http://www.w3.org/2001/XMLSchema}string
Part: name=processInstanceID
typeName={http://www.w3.org/2001/XMLSchema}string
Part: name=feedback
typeName={http://www.w3.org/2001/XMLSchema}string
Output: name=null
Message: name={http://demo_testje.wsdl}userReplyMessage
Part: name=return
typeName={http://www.w3.org/2001/XMLSchema}string
Operation: name=taskCompleted3
style=REQUEST_RESPONSE,1
Input: name=null
Message: name={http://demo_testje.wsdl}taskCompletedRequest
Part: name=userID
typeName={http://www.w3.org/2001/XMLSchema}int
Part: name=chosenCompletionValue
typeName={http://www.w3.org/2001/XMLSchema}string
Part: name=processInstanceID
typeName={http://www.w3.org/2001/XMLSchema}string
Part: name=feedback
typeName={http://www.w3.org/2001/XMLSchema}string
Output: name=null
Message: name={http://demo_testje.wsdl}userReplyMessage
Part: name=return
typeName={http://www.w3.org/2001/XMLSchema}string
Operation: name=taskCompleted6
style=REQUEST_RESPONSE,1
Input: name=null
Message: name={http://demo_testje.wsdl}taskCompletedRequest
Part: name=userID
typeName={http://www.w3.org/2001/XMLSchema}int
Part: name=chosenCompletionValue
typeName={http://www.w3.org/2001/XMLSchema}string
Part: name=processInstanceID
typeName={http://www.w3.org/2001/XMLSchema}string
Part: name=feedback
typeName={http://www.w3.org/2001/XMLSchema}string
Output: name=null
Message: name={http://demo_testje.wsdl}userReplyMessage
Part: name=return
typeName={http://www.w3.org/2001/XMLSchema}string,Operation: name=initiate
style=REQUEST_RESPONSE,1
Input: name=null
Message: name={http://demo_testje.wsdl}initiateRequest
Part: name=processInstanceID
typeName={http://www.w3.org/2001/XMLSchema}string
Output: name=null
Message: name={http://demo_testje.wsdl}userReplyMessage
Part: name=return
typeName={http://www.w3.org/2001/XMLSchema}string)
DEBUG - GeronimoLog.debug(66) | ODE routed to operation Operation: name=initiate
style=REQUEST_RESPONSE,1
Input: name=null
Message: name={http://demo_testje.wsdl}initiateRequest
Part: name=processInstanceID
typeName={http://www.w3.org/2001/XMLSchema}string
Output: name=null
Message: name={http://demo_testje.wsdl}userReplyMessage
Part: name=return
typeName={http://www.w3.org/2001/XMLSchema}string from service {http://demo_testje.wsdl}demo_testjeService
16:57:43,536 DEBUG [ODEService] ODE routed to operation Operation: name=initiate
style=REQUEST_RESPONSE,1
Input: name=null
Message: name={http://demo_testje.wsdl}initiateRequest
Part: name=processInstanceID
typeName={http://www.w3.org/2001/XMLSchema}string
Output: name=null
Message: name={http://demo_testje.wsdl}userReplyMessage
Part: name=return
typeName={http://www.w3.org/2001/XMLSchema}string from service {http://demo_testje.wsdl}demo_testjeService
DEBUG - GeronimoLog.trace(54) | register(prefix=soapenv, uri=http://schemas.xmlsoap.org/soap/envelope/)
16:57:43,546 DEBUG [NSContext] register(prefix=soapenv, uri=http://schemas.xmlsoap.org/soap/envelope/)
DEBUG - GeronimoLog.trace(54) | register(prefix=ns1, uri=http://demo_testje.wsdl)
16:57:43,546 DEBUG [NSContext] register(prefix=ns1, uri=http://demo_testje.wsdl)
DEBUG - GeronimoLog.debug(66) | Invoking ODE using MEX {MyRoleMex#hqejbhcnphr2e149g9im9r [Client hqejbhcnphr2e149g9im9q] calling {http://demo_testje.wsdl}demo_testjeService.initiate(...)}
16:57:43,556 DEBUG [ODEService] Invoking ODE using MEX {MyRoleMex#hqejbhcnphr2e149g9im9r [Client hqejbhcnphr2e149g9im9q] calling {http://demo_testje.wsdl}demo_testjeService.initiate(...)}
DEBUG - GeronimoLog.debug(66) | Message content:  <?xml version="1.0" encoding="UTF-8"?>
<message><processInstanceID xmlns:ns1="http://demo_testje.wsdl" xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/">demo_testje1183129063</processInstanceID></message>
16:57:43,566 DEBUG [ODEService] Message content:  <?xml version="1.0" encoding="UTF-8"?>
<message><processInstanceID xmlns:ns1="http://demo_testje.wsdl" xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/">demo_testje1183129063</processInstanceID></message>
DEBUG - GeronimoLog.debug(66) | Routed: svcQname {http://demo_testje.wsdl}demo_testjeService --> BpelProcess[{http://demo_testje}demo_testje-8]
16:57:43,596 DEBUG [BpelEngineImpl] Routed: svcQname {http://demo_testje.wsdl}demo_testjeService --> BpelProcess[{http://demo_testje}demo_testje-8]
DEBUG - GeronimoLog.debug(66) | invoke() EPR= null ==> BpelProcess[{http://demo_testje}demo_testje-8]
16:57:43,596 DEBUG [MyRoleMessageExchangeImpl] invoke() EPR= null ==> BpelProcess[{http://demo_testje}demo_testje-8]
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:57:43,646 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT JOB_NAME FROM QRTZ_JOB_DETAILS WHERE JOB_NAME = ? AND JOB_GROUP = ?
16:57:43,646 DEBUG [sql] prepareStmt: SELECT JOB_NAME FROM QRTZ_JOB_DETAILS WHERE JOB_NAME = ? AND JOB_GROUP = ?
DEBUG - GeronimoLog.debug(66) | bound (2,DEFAULT) (1,hqejbhcnphr2e149g9im9s)
16:57:43,656 DEBUG [sql] bound (2,DEFAULT) (1,hqejbhcnphr2e149g9im9s)
DEBUG - GeronimoLog.debug(66) | prepareStmt: INSERT INTO QRTZ_JOB_DETAILS (JOB_NAME, JOB_GROUP, DESCRIPTION, JOB_CLASS_NAME, IS_DURABLE, IS_VOLATILE, IS_STATEFUL, REQUESTS_RECOVERY, JOB_DATA)  VALUES(?, ?, ?, ?, ?, ?, ?, ?, ?)
16:57:43,666 DEBUG [sql] prepareStmt: INSERT INTO QRTZ_JOB_DETAILS (JOB_NAME, JOB_GROUP, DESCRIPTION, JOB_CLASS_NAME, IS_DURABLE, IS_VOLATILE, IS_STATEFUL, REQUESTS_RECOVERY, JOB_DATA)  VALUES(?, ?, ?, ?, ?, ?, ?, ?, ?)
DEBUG - GeronimoLog.debug(66) | bound (2,DEFAULT) (4,org.apache.ode.bpel.scheduler.quartz.JobImpl) (9,[B@117ee94) (8,true) (6,false) (1,hqejbhcnphr2e149g9im9s) (3,null) (7,false) (5,false)
16:57:43,666 DEBUG [sql] bound (2,DEFAULT) (4,org.apache.ode.bpel.scheduler.quartz.JobImpl) (9,[B@117ee94) (8,true) (6,false) (1,hqejbhcnphr2e149g9im9s) (3,null) (7,false) (5,false)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT TRIGGER_NAME FROM QRTZ_TRIGGERS WHERE TRIGGER_NAME = ? AND TRIGGER_GROUP = ?
16:57:43,676 DEBUG [sql] prepareStmt: SELECT TRIGGER_NAME FROM QRTZ_TRIGGERS WHERE TRIGGER_NAME = ? AND TRIGGER_GROUP = ?
DEBUG - GeronimoLog.debug(66) | bound (2,DEFAULT) (1,hqejbhcnphr2e149g9im9s.trigger)
16:57:43,686 DEBUG [sql] bound (2,DEFAULT) (1,hqejbhcnphr2e149g9im9s.trigger)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT TRIGGER_GROUP FROM QRTZ_PAUSED_TRIGGER_GRPS WHERE TRIGGER_GROUP = ?
16:57:43,686 DEBUG [sql] prepareStmt: SELECT TRIGGER_GROUP FROM QRTZ_PAUSED_TRIGGER_GRPS WHERE TRIGGER_GROUP = ?
DEBUG - GeronimoLog.debug(66) | bound (1,DEFAULT)
16:57:43,686 DEBUG [sql] bound (1,DEFAULT)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT TRIGGER_GROUP FROM QRTZ_PAUSED_TRIGGER_GRPS WHERE TRIGGER_GROUP = ?
16:57:43,696 DEBUG [sql] prepareStmt: SELECT TRIGGER_GROUP FROM QRTZ_PAUSED_TRIGGER_GRPS WHERE TRIGGER_GROUP = ?
DEBUG - GeronimoLog.debug(66) | bound (1,_$_ALL_GROUPS_PAUSED_$_)
16:57:43,696 DEBUG [sql] bound (1,_$_ALL_GROUPS_PAUSED_$_)
DEBUG - GeronimoLog.debug(66) | prepareStmt: INSERT INTO QRTZ_TRIGGERS (TRIGGER_NAME, TRIGGER_GROUP, JOB_NAME, JOB_GROUP, IS_VOLATILE, DESCRIPTION, NEXT_FIRE_TIME, PREV_FIRE_TIME, TRIGGER_STATE, TRIGGER_TYPE, START_TIME, END_TIME, CALENDAR_NAME, MISFIRE_INSTR, JOB_DATA)  VALUES(?, ?, ?, ?, ?, ?, ?,
?, ?, ?, ?, ?, ?, ?, ?)
16:57:43,706 DEBUG [sql] prepareStmt: INSERT INTO QRTZ_TRIGGERS (TRIGGER_NAME, TRIGGER_GROUP, JOB_NAME, JOB_GROUP, IS_VOLATILE, DESCRIPTION, NEXT_FIRE_TIME, PREV_FIRE_TIME, TRIGGER_STATE, TRIGGER_TYPE, START_TIME, END_TIME, CALENDAR_NAME, MISFIRE_INSTR, JOB_DATA)  VALUES(?, ?, ?, ?, ?, ?, ?, ?, ?, ?
, ?, ?, ?, ?, ?)
DEBUG - GeronimoLog.debug(66) | bound (15,null) (4,DEFAULT) (8,-1) (11,1183129063606) (3,hqejbhcnphr2e149g9im9s) (7,1183129063606) (12,0) (2,DEFAULT) (13,null) (9,WAITING) (6,null) (1,hqejbhcnphr2e149g9im9s.trigger) (14,0) (10,SIMPLE) (5,false)
16:57:43,716 DEBUG [sql] bound (15,null) (4,DEFAULT) (8,-1) (11,1183129063606) (3,hqejbhcnphr2e149g9im9s) (7,1183129063606) (12,0) (2,DEFAULT) (13,null) (9,WAITING) (6,null) (1,hqejbhcnphr2e149g9im9s.trigger) (14,0) (10,SIMPLE) (5,false)
DEBUG - GeronimoLog.debug(66) | prepareStmt: INSERT INTO QRTZ_SIMPLE_TRIGGERS (TRIGGER_NAME, TRIGGER_GROUP, REPEAT_COUNT, REPEAT_INTERVAL, TIMES_TRIGGERED)  VALUES(?, ?, ?, ?, ?)
16:57:43,716 DEBUG [sql] prepareStmt: INSERT INTO QRTZ_SIMPLE_TRIGGERS (TRIGGER_NAME, TRIGGER_GROUP, REPEAT_COUNT, REPEAT_INTERVAL, TIMES_TRIGGERED)  VALUES(?, ?, ?, ?, ?)
DEBUG - GeronimoLog.debug(66) | bound (2,DEFAULT) (4,0) (1,hqejbhcnphr2e149g9im9s.trigger) (3,0) (5,0)
16:57:43,726 DEBUG [sql] bound (2,DEFAULT) (4,0) (1,hqejbhcnphr2e149g9im9s.trigger) (3,0) (5,0)
DEBUG - GeronimoLog.debug(66) | close
16:57:43,726 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | Commiting ODE MEX {MyRoleMex#hqejbhcnphr2e149g9im9r [Client hqejbhcnphr2e149g9im9q] calling {http://demo_testje.wsdl}demo_testjeService.initiate(...)}
16:57:43,776 DEBUG [ODEService] Commiting ODE MEX {MyRoleMex#hqejbhcnphr2e149g9im9r [Client hqejbhcnphr2e149g9im9q] calling {http://demo_testje.wsdl}demo_testjeService.initiate(...)}
DEBUG - GeronimoLog.debug(66) | Commiting transaction.
16:57:43,776 DEBUG [ODEService] Commiting transaction.
DEBUG - GeronimoLog.debug(66) | getNonManagedTXConnection()
16:57:43,786 DEBUG [JobStoreJTA] getNonManagedTXConnection()
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:57:43,786 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | Setting isolation level to 2
16:57:43,796 DEBUG [sql] Setting isolation level to 2
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is desired by: ODE_QuartzSchedulerThread
16:57:43,796 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is desired by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is being obtained: ODE_QuartzSchedulerThread
16:57:43,806 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is being obtained: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' given to: ODE_QuartzSchedulerThread
16:57:43,806 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' given to: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is desired by: ODE_QuartzSchedulerThread
16:57:43,806 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is desired by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is being obtained: ODE_QuartzSchedulerThread
16:57:43,816 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is being obtained: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' given to: ODE_QuartzSchedulerThread
16:57:43,816 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' given to: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
16:57:43,816 DEBUG [JobStoreJTA] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
16:57:43,826 DEBUG [sql] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
DEBUG - GeronimoLog.debug(66) | bound (2,WAITING) (4,1183129003816) (1,MISFIRED) (3,WAITING)
16:57:43,826 DEBUG [JobStoreJTA] bound (2,WAITING) (4,1183129003816) (1,MISFIRED) (3,WAITING)
DEBUG - GeronimoLog.debug(66) | bound (2,WAITING) (4,1183129003816) (1,MISFIRED) (3,WAITING)
16:57:43,826 DEBUG [sql] bound (2,WAITING) (4,1183129003816) (1,MISFIRED) (3,WAITING)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
16:57:43,836 DEBUG [JobStoreJTA] prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
16:57:43,836 DEBUG [sql] prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
DEBUG - GeronimoLog.debug(66) | bound (1,WAITING)
16:57:43,846 DEBUG [JobStoreJTA] bound (1,WAITING)
DEBUG - GeronimoLog.debug(66) | bound (1,WAITING)
16:57:43,846 DEBUG [sql] bound (1,WAITING)
DEBUG - GeronimoLog.debug(66) | COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@5f3bad
16:57:43,846 DEBUG [JobStoreJTA] COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@5f3bad
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' retuned by: ODE_QuartzSchedulerThread
16:57:43,866 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' retuned by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' retuned by: ODE_QuartzSchedulerThread
16:57:43,866 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' retuned by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | close
16:57:43,876 DEBUG [JobStoreJTA] close
DEBUG - GeronimoLog.debug(66) | close
16:57:43,876 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:57:43,886 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? FOR UPDATE
16:57:43,896 DEBUG [sql] prepareStmt: SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? FOR UPDATE
DEBUG - GeronimoLog.debug(66) | bound (1,0)
16:57:43,896 DEBUG [sql] bound (1,0)
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND SEQUENCE_VALUE = ?
16:57:43,906 DEBUG [sql] prepareStmt: UPDATE OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND SEQUENCE_VALUE = ?
DEBUG - GeronimoLog.debug(66) | bound (2,0) (1,551) (3,501)
16:57:43,906 DEBUG [sql] bound (2,0) (1,551) (3,501)
DEBUG - GeronimoLog.debug(66) | close
16:57:43,946 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:57:43,946 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | 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 (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
16:57:43,966 DEBUG [sql] 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 (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
DEBUG - GeronimoLog.debug(66) | bound (15,null) (4,hqejbhcnphr2e149g9im9q) (19,null) (8,77) (23,501) (11,null) (16,null) (18,ASYNC) (3,null) (7,null) (12,initiate) (22,null) (17,0) (2,{http://demo_testje.wsdl}demo_testjeService) (13,0) (9,null) (21,null) (6,UKNOWN_ENDPOINT) (
1,hqejbhcnphr2e149g9im9r) (20,null) (14,UNKNOWN) (24,null) (10,null) (5,null)
16:57:43,976 DEBUG [sql] bound (15,null) (4,hqejbhcnphr2e149g9im9q) (19,null) (8,77) (23,501) (11,null) (16,null) (18,ASYNC) (3,null) (7,null) (12,initiate) (22,null) (17,0) (2,{http://demo_testje.wsdl}demo_testjeService) (13,0) (9,null) (21,null) (6,UKNOWN_ENDPOINT) (1,hqejb
hcnphr2e149g9im9r) (20,null) (14,UNKNOWN) (24,null) (10,null) (5,null)
DEBUG - GeronimoLog.debug(66) | prepareStmt: INSERT INTO ODE_MESSAGE (MESSAGE_ID, DATA, TYPE, MESSAGE_EXCHANGE_ID) VALUES (?, ?, ?, ?)
16:57:44,006 DEBUG [sql] prepareStmt: INSERT INTO ODE_MESSAGE (MESSAGE_ID, DATA, TYPE, MESSAGE_EXCHANGE_ID) VALUES (?, ?, ?, ?)
DEBUG - GeronimoLog.debug(66) | bound (4,hqejbhcnphr2e149g9im9r) (1,501) (3,{http://demo_testje.wsdl}initiateRequest)
16:57:44,006 DEBUG [sql] bound (4,hqejbhcnphr2e149g9im9r) (1,501) (3,{http://demo_testje.wsdl}initiateRequest)
DEBUG - GeronimoLog.debug(66) | close
16:57:44,036 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | getNonManagedTXConnection()
16:58:09,419 DEBUG [JobStoreJTA] getNonManagedTXConnection()
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:58:09,429 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | Setting isolation level to 2
16:58:09,429 DEBUG [sql] Setting isolation level to 2
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is desired by: ODE_QuartzSchedulerThread
16:58:09,429 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is desired by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is being obtained: ODE_QuartzSchedulerThread
16:58:09,429 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is being obtained: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' given to: ODE_QuartzSchedulerThread
16:58:09,429 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' given to: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is desired by: ODE_QuartzSchedulerThread
16:58:09,429 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is desired by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is being obtained: ODE_QuartzSchedulerThread
16:58:09,429 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is being obtained: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' given to: ODE_QuartzSchedulerThread
16:58:09,429 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' given to: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
16:58:09,429 DEBUG [JobStoreJTA] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
16:58:09,439 DEBUG [sql] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
DEBUG - GeronimoLog.debug(66) | bound (2,WAITING) (4,1183129029429) (1,MISFIRED) (3,WAITING)
16:58:09,439 DEBUG [JobStoreJTA] bound (2,WAITING) (4,1183129029429) (1,MISFIRED) (3,WAITING)
DEBUG - GeronimoLog.debug(66) | bound (2,WAITING) (4,1183129029429) (1,MISFIRED) (3,WAITING)
16:58:09,439 DEBUG [sql] bound (2,WAITING) (4,1183129029429) (1,MISFIRED) (3,WAITING)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
16:58:09,439 DEBUG [JobStoreJTA] prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
16:58:09,439 DEBUG [sql] prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
DEBUG - GeronimoLog.debug(66) | bound (1,WAITING)
16:58:09,439 DEBUG [JobStoreJTA] bound (1,WAITING)
DEBUG - GeronimoLog.debug(66) | bound (1,WAITING)
16:58:09,439 DEBUG [sql] bound (1,WAITING)
DEBUG - GeronimoLog.debug(66) | COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@8b60a
16:58:09,439 DEBUG [JobStoreJTA] COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@8b60a
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' retuned by: ODE_QuartzSchedulerThread
16:58:09,449 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' retuned by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' retuned by: ODE_QuartzSchedulerThread
16:58:09,449 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' retuned by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | close
16:58:09,449 DEBUG [JobStoreJTA] close
DEBUG - GeronimoLog.debug(66) | close
16:58:09,449 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | MisfireHandler: scanning for misfires...
16:58:26,832 DEBUG [JobStoreJTA] MisfireHandler: scanning for misfires...
DEBUG - GeronimoLog.debug(66) | doRecoverMisfires() callled
16:58:26,832 DEBUG [JobStoreJTA] doRecoverMisfires() callled
DEBUG - GeronimoLog.debug(66) | getNonManagedTXConnection()
16:58:26,832 DEBUG [JobStoreJTA] getNonManagedTXConnection()
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:58:26,832 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | Setting isolation level to 2
16:58:26,832 DEBUG [sql] Setting isolation level to 2
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is desired by: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
16:58:26,832 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is desired by: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is being obtained: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
16:58:26,842 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is being obtained: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' given to: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
16:58:26,842 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' given to: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
16:58:26,842 DEBUG [JobStoreJTA] prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
16:58:26,842 DEBUG [sql] prepareStmt: SELECT TRIGGER_NAME, TRIGGER_GROUP FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ?
DEBUG - GeronimoLog.debug(66) | bound (1,MISFIRED)
16:58:26,842 DEBUG [JobStoreJTA] bound (1,MISFIRED)
DEBUG - GeronimoLog.debug(66) | bound (1,MISFIRED)
16:58:26,842 DEBUG [sql] bound (1,MISFIRED)
DEBUG - GeronimoLog.debug(66) | Found 0 triggers that missed their scheduled fire-time.
16:58:26,842 DEBUG [JobStoreJTA] Found 0 triggers that missed their scheduled fire-time.
DEBUG - GeronimoLog.debug(66) | COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@28318d
16:58:26,842 DEBUG [JobStoreJTA] COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@28318d
DEBUG - GeronimoLog.debug(66) | doRecoverMisfires() returned moreToDo = false
16:58:26,842 DEBUG [JobStoreJTA] doRecoverMisfires() returned moreToDo = false
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' retuned by: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
16:58:26,842 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' retuned by: QuartzScheduler_ODE-hqejbhcnphr2e149g9im9p_MisfireHandler
DEBUG - GeronimoLog.debug(66) | close
16:58:26,842 DEBUG [JobStoreJTA] close
DEBUG - GeronimoLog.debug(66) | close
16:58:26,852 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | getNonManagedTXConnection()
16:58:36,124 DEBUG [JobStoreJTA] getNonManagedTXConnection()
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:58:36,124 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | Setting isolation level to 2
16:58:36,124 DEBUG [sql] Setting isolation level to 2
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is desired by: ODE_QuartzSchedulerThread
16:58:36,124 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is desired by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is being obtained: ODE_QuartzSchedulerThread
16:58:36,134 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is being obtained: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' given to: ODE_QuartzSchedulerThread
16:58:36,134 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' given to: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is desired by: ODE_QuartzSchedulerThread
16:58:36,134 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is desired by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is being obtained: ODE_QuartzSchedulerThread
16:58:36,134 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is being obtained: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' given to: ODE_QuartzSchedulerThread
16:58:36,134 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' given to: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
16:58:36,134 DEBUG [JobStoreJTA] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
16:58:36,134 DEBUG [sql] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
DEBUG - GeronimoLog.debug(66) | bound (2,WAITING) (4,1183129056134) (1,MISFIRED) (3,WAITING)
16:58:36,134 DEBUG [JobStoreJTA] bound (2,WAITING) (4,1183129056134) (1,MISFIRED) (3,WAITING)
DEBUG - GeronimoLog.debug(66) | bound (2,WAITING) (4,1183129056134) (1,MISFIRED) (3,WAITING)
16:58:36,134 DEBUG [sql] bound (2,WAITING) (4,1183129056134) (1,MISFIRED) (3,WAITING)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
16:58:36,134 DEBUG [JobStoreJTA] prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
16:58:36,134 DEBUG [sql] prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
DEBUG - GeronimoLog.debug(66) | bound (1,WAITING)
16:58:36,144 DEBUG [JobStoreJTA] bound (1,WAITING)
DEBUG - GeronimoLog.debug(66) | bound (1,WAITING)
16:58:36,144 DEBUG [sql] bound (1,WAITING)
DEBUG - GeronimoLog.debug(66) | COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@ef9525
16:58:36,144 DEBUG [JobStoreJTA] COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@ef9525
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' retuned by: ODE_QuartzSchedulerThread
16:58:36,144 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' retuned by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' retuned by: ODE_QuartzSchedulerThread
16:58:36,144 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' retuned by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | close
16:58:36,144 DEBUG [JobStoreJTA] close
DEBUG - GeronimoLog.debug(66) | close
16:58:36,144 DEBUG [sql] close
DEBUG - GeronimoLog.debug(66) | getNonManagedTXConnection()
16:59:01,827 DEBUG [JobStoreJTA] getNonManagedTXConnection()
DEBUG - GeronimoLog.debug(66) | getConnection (tx=2)
16:59:01,827 DEBUG [sql] getConnection (tx=2)
DEBUG - GeronimoLog.debug(66) | Setting isolation level to 2
16:59:01,837 DEBUG [sql] Setting isolation level to 2
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is desired by: ODE_QuartzSchedulerThread
16:59:01,837 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is desired by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' is being obtained: ODE_QuartzSchedulerThread
16:59:01,837 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' is being obtained: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' given to: ODE_QuartzSchedulerThread
16:59:01,837 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' given to: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is desired by: ODE_QuartzSchedulerThread
16:59:01,837 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is desired by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' is being obtained: ODE_QuartzSchedulerThread
16:59:01,837 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' is being obtained: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' given to: ODE_QuartzSchedulerThread
16:59:01,837 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' given to: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
16:59:01,837 DEBUG [JobStoreJTA] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
DEBUG - GeronimoLog.debug(66) | prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
16:59:01,837 DEBUG [sql] prepareStmt: UPDATE QRTZ_TRIGGERS SET TRIGGER_STATE = ? WHERE (TRIGGER_STATE = ? OR TRIGGER_STATE = ?) AND NEXT_FIRE_TIME < ?
DEBUG - GeronimoLog.debug(66) | bound (2,WAITING) (4,1183129081837) (1,MISFIRED) (3,WAITING)
16:59:01,847 DEBUG [JobStoreJTA] bound (2,WAITING) (4,1183129081837) (1,MISFIRED) (3,WAITING)
DEBUG - GeronimoLog.debug(66) | bound (2,WAITING) (4,1183129081837) (1,MISFIRED) (3,WAITING)
16:59:01,847 DEBUG [sql] bound (2,WAITING) (4,1183129081837) (1,MISFIRED) (3,WAITING)
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
16:59:01,847 DEBUG [JobStoreJTA] prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
DEBUG - GeronimoLog.debug(66) | prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
16:59:01,847 DEBUG [sql] prepareStmt: SELECT MIN(NEXT_FIRE_TIME) AS ALIAS_NXT_FR_TM FROM QRTZ_TRIGGERS WHERE TRIGGER_STATE = ? AND NEXT_FIRE_TIME >= 0
DEBUG - GeronimoLog.debug(66) | bound (1,WAITING)
16:59:01,857 DEBUG [JobStoreJTA] bound (1,WAITING)
DEBUG - GeronimoLog.debug(66) | bound (1,WAITING)
16:59:01,857 DEBUG [sql] bound (1,WAITING)
DEBUG - GeronimoLog.debug(66) | COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@1d1695e
16:59:01,867 DEBUG [JobStoreJTA] COMMIT: org.apache.ode.utils.LoggingConnectionWrapper@1d1695e
DEBUG - GeronimoLog.debug(66) | Lock 'TRIGGER_ACCESS' retuned by: ODE_QuartzSchedulerThread
16:59:01,867 DEBUG [NotSoSimpleSemaphore] Lock 'TRIGGER_ACCESS' retuned by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | Lock 'JOB_ACCESS' retuned by: ODE_QuartzSchedulerThread
16:59:01,867 DEBUG [NotSoSimpleSemaphore] Lock 'JOB_ACCESS' retuned by: ODE_QuartzSchedulerThread
DEBUG - GeronimoLog.debug(66) | close
16:59:01,867 DEBUG [JobStoreJTA] close
DEBUG - GeronimoLog.debug(66) | close
16:59:01,867 DEBUG [sql] close

-----Original Message-----
From: Alex Boisvert [mailto:boisvert@intalio.com] 
Sent: woensdag 27 juni 2007 17:58
To: ode-user@incubator.apache.org
Subject: Re: Running Ode using an external database (MySql)

Can you set the log categories "org.apache.ode" and "httpclient" to DEBUG
and post your entire log somewhere?  The logs you've provided so far did not
reveal the root cause of the problem.

alex

On 6/27/07, René Bos <rene@rpsoft.nl> wrote:
>
> Do you have any idea to fix this?
>
> René
>
> René Bos schreef op 21-6-2007 16:46:
> > I had all org.apache.ode logs on DEBUG except the .erp one. Turned that
> one also on, but no difference.
> >
> > I tried without the external database, than this is logged at the point
> the timeout occurs.
> >
> > DEBUG - GeronimoLog.debug(66) | Invoking ODE using MEX
> {MyRoleMex#hqejbhcnphr2d950k7qbfs [Client hqejbhcnphr2d950k7qbfr] calling {
> http://Test_T-Rex.wsdl}Test_T-RexService.initiate(...)}
> > 12:21:35,443 DEBUG [ODEService] Invoking ODE using MEX
> {MyRoleMex#hqejbhcnphr2d950k7qbfs [Client hqejbhcnphr2d950k7qbfr] calling {
> http://Test_T-Rex.wsdl}Test_T-RexService.initiate(...)}
> > DEBUG - GeronimoLog.debug(66) | Message content:  <?xml version="1.0"
> encoding="UTF-8"?>
> > <message><processInstanceID xmlns:ns1="http://Test_T-Rex.wsdl"
> xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/
> ">Test_T-Rex1182421295</processInstanceID></message>
> > 12:21:35,443 DEBUG [ODEService] Message content:  <?xml version="1.0"
> encoding="UTF-8"?>
> > <message><processInstanceID xmlns:ns1="http://Test_T-Rex.wsdl"
> xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/
> ">Test_T-Rex1182421295</processInstanceID></message>
> > DEBUG - GeronimoLog.debug(66) | Routed: svcQname {
> http://Test_T-Rex.wsdl}Test_T-RexService --> BpelProcess[{
> http://Test_T-Rex}Test_T-Rex-2]
> > 12:21:35,473 DEBUG [BpelEngineImpl] Routed: svcQname {
> http://Test_T-Rex.wsdl}Test_T-RexService --> BpelProcess[{
> http://Test_T-Rex}Test_T-Rex-2]
> > DEBUG - GeronimoLog.debug(66) | invoke() EPR= null ==> BpelProcess[{
> http://Test_T-Rex}Test_T-Rex-2]
> > 12:21:35,473 DEBUG [MyRoleMessageExchangeImpl] invoke() EPR= null ==>
> BpelProcess[{http://Test_T-Rex}Test_T-Rex-2]
> > DEBUG - GeronimoLog.debug(66) | Commiting ODE MEX
> {MyRoleMex#hqejbhcnphr2d950k7qbfs [Client hqejbhcnphr2d950k7qbfr] calling {
> http://Test_T-Rex.wsdl}Test_T-RexService.initiate(...)}
> > 12:21:35,953 DEBUG [ODEService] Commiting ODE MEX
> {MyRoleMex#hqejbhcnphr2d950k7qbfs [Client hqejbhcnphr2d950k7qbfr] calling {
> http://Test_T-Rex.wsdl}Test_T-RexService.initiate(...)}
> > DEBUG - GeronimoLog.debug(66) | Commiting transaction.
> > 12:21:35,953 DEBUG [ODEService] Commiting transaction.
> > DEBUG - GeronimoLog.debug(66) | >> handleWorkEvent(jobData=
> org.quartz.JobDataMap@a3ed9f)
> > 12:21:36,484 DEBUG [BpelProcess] >> handleWorkEvent(jobData=
> org.quartz.JobDataMap@a3ed9f)
> > DEBUG - GeronimoLog.debug(66) | InvokeInternal event for mexid
> hqejbhcnphr2d950k7qbfs
> > 12:21:36,494 DEBUG [BpelProcess] InvokeInternal event for mexid
> hqejbhcnphr2d950k7qbfs
> > DEBUG - GeronimoLog.trace(54) | >> {PartnerLinkRole-Test_T-
> RexLink.initiator on {
> http://Test_T-Rex.wsdl}Test_T-RexService:Test_T-RexPort}:inputMsgRcvd(messageExchange={MyRoleMex#hqejbhcnphr2d950k7qbfs[Client hqejbhcnphr2d950k7qbfr] calling {
> http://Aveleij
> > nintranet_T-Rex.wsdl}Test_T-RexService.initiate(...)})
> > 12:21:36,614 DEBUG [BpelProcess] >> {PartnerLinkRole-Test_T-
> RexLink.initiator on {
> http://Test_T-Rex.wsdl}Test_T-RexService:Test_T-RexPort}:inputMsgRcvd(messageExchange={MyRoleMex#hqejbhcnphr2d950k7qbfs[Client hqejbhcnphr2d950k7qbfr] calling {
> http://Avelei
> > jnintranet_T-Rex.wsdl}Test_T-RexService.initiate(...)})
> > DEBUG - GeronimoLog.trace(54) |
> Mex[hqejbhcnphr2d950k7qbfs].setPortOp(PortType: name={
> http://Test_T-Rex.wsdl}Test_T-RexPortType
> >
> > Maybe you can reproduce it by yourself? It is very simple to setup in
> Tomcat (I'm using tomcat..)
> > Rene
> >
> > -----Original Message-----
> > From: Matthieu Riou [mailto:matthieu.riou@gmail.com]
> > Sent: donderdag 21 juni 2007 16:36
> > To: ode-user@incubator.apache.org
> > Subject: Re: Running Ode using an external database (MySql)
> >
> > Okay. It's weird that you're still getting this NPE but anyway there's
> > something wrong happening in the engine that prevents it from building
> the
> > reply (and it then timeouts).
> >
> > Could you set the log category "org.apache.ode" to the debug log level?
> With
> > more details regarding execution I might be able to guess what's going
> > wrong. Given that the only difference is that you're running on MySQL I
> > guess we get a database error but it's just really weird that it doesn't
> > bubble up and appear in the logs.
> >
> > Matthieu
> >
> > On 6/21/07, René Bos <r.bos@pagelink.nl> wrote:
> >
> >> Hi!
> >>
> >> I double checked it for you, but it isn't there. Only the patched
> kernel.
> >>
> >> I also double checked the error, but it is the same as the one I
> posted.
> >>
> >> René
> >>
> >> -----Original Message-----
> >> From: Matthieu Riou [mailto:matthieu.riou@gmail.com]
> >> Sent: donderdag 21 juni 2007 16:04
> >> To: ode-user@incubator.apache.org
> >> Subject: Re: Running Ode using an external database (MySql)
> >>
> >> Hi Rene,
> >>
> >> Would you make sure that the lib directory of the ODE webapp only
> contains
> >> an axis2-kernel-intalio-1.1.1b.jar and no axis2-kernel-x.x.x.jar ?
> >>
> >> Thanks,
> >> Matthieu
> >>
> >> On 6/21/07, René Bos <r.bos@pagelink.nl> wrote:
> >>
> >>> Something went wrong with the attachment of my previous message, here
> it
> >>> is:
> >>>
> >>> INFO: Starting Coyote HTTP/1.1 on http-8080
> >>> 20-jun-2007 10:39:40 org.apache.jk.common.ChannelSocket init
> >>> INFO: JK: ajp13 listening on /0.0.0.0:8009
> >>> 20-jun-2007 10:39:40 org.apache.jk.server.JkMain start
> >>> INFO: Jk running ID=0 time=0/120  config=null
> >>> 20-jun-2007 10:39:41 org.apache.catalina.storeconfig.StoreLoader load
> >>> INFO: Find registry server-registry.xml at classpath resource
> >>> DEBUG - GeronimoLog.debug(66) | Checking for Operation using WSAAction
> :
> >>> urn:anonOutInOp
> >>> 10:39:41,144 DEBUG [ODEAxisDispatcher] Checking for Operation using
> >>> WSAAction : urn:anonOutInOp
> >>> DEBUG - GeronimoLog.debug(66) | Checking for Operation using SOAP
> >>>
> >> message
> >>
> >>> body's first child's local name : initiate
> >>> 10:39:41,164 DEBUG [ODEAxisDispatcher] Checking for Operation using
> SOAP
> >>> message body's first child's local name : initiate
> >>> DEBUG - GeronimoLog.debug(66) | Found operation
> >>> org.apache.axis2.description.InOutAxisOperation@6399ae
> >>> 10:39:41,164 DEBUG [ODEAxisDispatcher] Found operation
> >>> org.apache.axis2.description.InOutAxisOperation@6399ae
> >>> 20-jun-2007 10:39:41 org.apache.catalina.startup.Catalina start
> >>> INFO: Server startup in 22209 ms
> >>> DEBUG - GeronimoLog.debug(66) | Received request message for Test_T-
> >>> Rex.initiate
> >>> 10:39:41,234 DEBUG [ODEMessageReceiver] Received request message for
> >>> Test_T-Rex.initiate
> >>> DEBUG - GeronimoLog.debug(66) | Starting transaction.
> >>> 10:39:41,244 DEBUG [ODEService] Starting transaction.
> >>> DEBUG - GeronimoLog.debug(66) | Routed: svcQname {
> >>> http://Test_T-Rex.wsdl}Test_T-RexService --> BpelProcess[{
> >>> http://Test_T-Rex}Test_T-Rex-5]
> >>> 10:39:41,254 DEBUG [BpelEngineImpl] Routed: svcQname {
> >>> http://Test_T-Rex.wsdl}Test_T-RexService --> BpelProcess[{
> >>> http://Test_T-Rex}Test_T-Rex-5]
> >>> DEBUG - GeronimoLog.trace(54) |
> >>> Mex[hqejbhcnphr2d5prdws2vv].setPortOp(PortType: name={
> >>> http://Test_T-Rex.wsdl}Test_T-RexPortType
> >>> Operation: name=initiate
> >>> style=REQUEST_RESPONSE,1
> >>> Input: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}initiateRequest
> >>> Part: name=processInstanceID
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Output: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}userReplyMessage
> >>> Part: name=return
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Operation: name=taskCompleted3
> >>> style=REQUEST_RESPONSE,1
> >>> Input: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}taskCompletedRequest
> >>> Part: name=userID
> >>> typeName={http://www.w3.org/2001/XMLSchema}int
> >>> Part: name=chosenCompletionValue
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Part: name=processInstanceID
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Part: name=feedback
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Output: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}userReplyMessage
> >>> Part: name=return
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Operation: name=taskCompleted4
> >>> style=REQUEST_RESPONSE,1
> >>> Input: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}taskCompletedRequest
> >>> Part: name=userID
> >>> typeName={http://www.w3.org/2001/XMLSchema}int
> >>> Part: name=chosenCompletionValue
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Part: name=processInstanceID
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Part: name=feedback
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Output: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}userReplyMessage
> >>> Part: name=return
> >>> typeName={http://www.w3.org/2001/XMLSchema}string,Operation:
> >>>
> >> name=initiate
> >>
> >>> style=REQUEST_RESPONSE,1
> >>> Input: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}initiateRequest
> >>> Part: name=processInstanceID
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Output: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}userReplyMessage
> >>> Part: name=return
> >>> typeName={http://www.w3.org/2001/XMLSchema}string)
> >>> 10:39:41,295 DEBUG [MessageExchangeImpl]
> >>> Mex[hqejbhcnphr2d5prdws2vv].setPortOp(PortType: name={
> >>> http://Test_T-Rex.wsdl}Test_T-RexPortType
> >>> Operation: name=initiate
> >>> style=REQUEST_RESPONSE,1
> >>> Input: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}initiateRequest
> >>> Part: name=processInstanceID
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Output: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}userReplyMessage
> >>> Part: name=return
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Operation: name=taskCompleted3
> >>> style=REQUEST_RESPONSE,1
> >>> Input: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}taskCompletedRequest
> >>> Part: name=userID
> >>> typeName={http://www.w3.org/2001/XMLSchema}int
> >>> Part: name=chosenCompletionValue
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Part: name=processInstanceID
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Part: name=feedback
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Output: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}userReplyMessage
> >>> Part: name=return
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Operation: name=taskCompleted4
> >>> style=REQUEST_RESPONSE,1
> >>> Input: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}taskCompletedRequest
> >>> Part: name=userID
> >>> typeName={http://www.w3.org/2001/XMLSchema}int
> >>> Part: name=chosenCompletionValue
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Part: name=processInstanceID
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Part: name=feedback
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Output: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}userReplyMessage
> >>> Part: name=return
> >>> typeName={http://www.w3.org/2001/XMLSchema}string,Operation:
> >>>
> >> name=initiate
> >>
> >>> style=REQUEST_RESPONSE,1
> >>> Input: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}initiateRequest
> >>> Part: name=processInstanceID
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Output: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}userReplyMessage
> >>> Part: name=return
> >>> typeName={http://www.w3.org/2001/XMLSchema}string)
> >>> DEBUG - GeronimoLog.debug(66) | ODE routed to operation Operation:
> >>> name=initiate
> >>> style=REQUEST_RESPONSE,1
> >>> Input: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}initiateRequest
> >>> Part: name=processInstanceID
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Output: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}userReplyMessage
> >>> Part: name=return
> >>> typeName={http://www.w3.org/2001/XMLSchema}string from service {
> >>> http://Test_T-Rex.wsdl}Test_T-RexService
> >>> 10:39:41,475 DEBUG [ODEService] ODE routed to operation Operation:
> >>> name=initiate
> >>> style=REQUEST_RESPONSE,1
> >>> Input: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}initiateRequest
> >>> Part: name=processInstanceID
> >>> typeName={http://www.w3.org/2001/XMLSchema}string
> >>> Output: name=null
> >>> Message: name={http://Test_T-Rex.wsdl}userReplyMessage
> >>> Part: name=return
> >>> typeName={http://www.w3.org/2001/XMLSchema}string from service {
> >>> http://Test_T-Rex.wsdl}Test_T-RexService
> >>> DEBUG - GeronimoLog.debug(66) | Invoking ODE using MEX
> >>> {MyRoleMex#hqejbhcnphr2d5prdws2vv [Client hqejbhcnphr2d5prdws2vu]
> >>>
> >> calling {
> >>
> >>> http://Test_T-Rex.wsdl}Test_T-RexService.initiate(...)}
> >>> 10:39:41,535 DEBUG [ODEService] Invoking ODE using MEX
> >>> {MyRoleMex#hqejbhcnphr2d5prdws2vv [Client hqejbhcnphr2d5prdws2vu]
> >>>
> >> calling {
> >>
> >>> http://Test_T-Rex.wsdl}Test_T-RexService.initiate(...)}
> >>> DEBUG - GeronimoLog.debug(66) | Message content:  <?xml version="1.0"
> >>> encoding="UTF-8"?>
> >>> <message><processInstanceID xmlns:ns1="http://Test_T-Rex.wsdl"
> >>> xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/
> >>> ">Test_T-Rex1182328778</processInstanceID></message>
> >>> 10:39:41,535 DEBUG [ODEService] Message content:  <?xml version="1.0"
> >>> encoding="UTF-8"?>
> >>> <message><processInstanceID xmlns:ns1="http://Test_T-Rex.wsdl"
> >>> xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/
> >>> ">Test_T-Rex1182328778</processInstanceID></message>
> >>> DEBUG - GeronimoLog.debug(66) | Routed: svcQname {
> >>> http://Test_T-Rex.wsdl}Test_T-RexService --> BpelProcess[{
> >>> http://Test_T-Rex}Test_T-Rex-5]
> >>> 10:39:41,555 DEBUG [BpelEngineImpl] Routed: svcQname {
> >>> http://Test_T-Rex.wsdl}Test_T-RexService --> BpelProcess[{
> >>> http://Test_T-Rex}Test_T-Rex-5]
> >>> DEBUG - GeronimoLog.debug(66) | invoke() EPR= null ==> BpelProcess[{
> >>> http://Test_T-Rex}Test_T-Rex-5]
> >>> 10:39:41,565 DEBUG [MyRoleMessageExchangeImpl] invoke() EPR= null ==>
> >>> BpelProcess[{http://Test_T-Rex}Test_T-Rex-5]
> >>> DEBUG - GeronimoLog.debug(66) | Commiting ODE MEX
> >>> {MyRoleMex#hqejbhcnphr2d5prdws2vv [Client hqejbhcnphr2d5prdws2vu]
> >>>
> >> calling {
> >>
> >>> http://Test_T-Rex.wsdl}Test_T-RexService.initiate(...)}
> >>> 10:39:41,665 DEBUG [ODEService] Commiting ODE MEX
> >>> {MyRoleMex#hqejbhcnphr2d5prdws2vv [Client hqejbhcnphr2d5prdws2vu]
> >>>
> >> calling {
> >>
> >>> http://Test_T-Rex.wsdl}Test_T-RexService.initiate(...)}
> >>> DEBUG - GeronimoLog.debug(66) | Commiting transaction.
> >>> 10:39:41,665 DEBUG [ODEService] Commiting transaction.
> >>> ERROR - GeronimoLog.error(104) | Timeout or execution error when
> waiting
> >>> for response to MEX {MyRoleMex#hqejbhcnphr2d5prdws2vv [Client
> >>> hqejbhcnphr2d5prdws2vu] calling {
> >>> http://Test_T-Rex.wsdl}Test_T-RexService.initiate(...)}
> >>> java.util.concurrent.TimeoutException: Message excha
> >>> nge
> >>>
> >>>
> >>
> org.apache.ode.bpel.engine.MyRoleMessageExchangeImpl$ResponseFuture@1dd8664timedoutwhen waiting for a response!
> >>
> >>> 10:41:43,037 ERROR [ODEService] Timeout or execution error when
> waiting
> >>> for response to MEX {MyRoleMex#hqejbhcnphr2d5prdws2vv [Client
> >>> hqejbhcnphr2d5prdws2vu] calling {
> >>> http://Test_T-Rex.wsdl}Test_T-RexService.initiate(...)}
> >>> java.util.concurrent.TimeoutException: Message exchan
> >>> ge
> >>>
> >>>
> >>
> org.apache.ode.bpel.engine.MyRoleMessageExchangeImpl$ResponseFuture@1dd8664timedoutwhen waiting for a response!
> >>
> >>> ERROR - GeronimoLog.error(108) | Servlet.service() for servlet
> >>>
> >> AxisServlet
> >>
> >>> threw exception
> >>> java.lang.NullPointerException
> >>>         at
> >>>
> >>>
> >>
> org.apache.axis2.engine.AxisEngine.extractFaultInformationFromMessageContext
> >>
> >>> (AxisEngine.java:353)
> >>>         at
> org.apache.axis2.engine.AxisEngine.createFaultMessageContext(
> >>> AxisEngine.java:249)
> >>>         at org.apache.axis2.transport.http.AxisServlet.handleFault(
> >>> AxisServlet.java:317)
> >>>         at org.apache.axis2.transport.http.AxisServlet.doPost(
> >>> AxisServlet.java:277)
> >>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java
> :709)
> >>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java
> :802)
> >>>         at
> >>> org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(
> >>> ApplicationFilterChain.java:252)
> >>>         at org.apache.catalina.core.ApplicationFilterChain.doFilter(
> >>> ApplicationFilterChain.java:173)
> >>>         at org.apache.catalina.core.StandardWrapperValve.invoke(
> >>> StandardWrapperValve.java:213)
> >>>         at org.apache.catalina.core.StandardContextValve.invoke(
> >>> StandardContextValve.java:178)
> >>>         at org.apache.catalina.core.StandardHostValve.invoke(
> >>> StandardHostValve.java:126)
> >>>         at org.apache.catalina.valves.ErrorReportValve.invoke(
> >>> ErrorReportValve.java:105)
> >>>         at org.apache.catalina.core.StandardEngineValve.invoke(
> >>> StandardEngineValve.java:107)
> >>>         at org.apache.catalina.connector.CoyoteAdapter.service(
> >>> CoyoteAdapter.java:148)
> >>>         at org.apache.coyote.http11.Http11Processor.process(
> >>> Http11Processor.java:869)
> >>>         at
> >>>
> >>>
> >>
> org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection
> >>
> >>> (Http11BaseProtocol.java:664)
> >>>         at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(
> >>> PoolTcpEndpoint.java:527)
> >>>         at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt
> (
> >>> LeaderFollowerWorkerThread.java:80)
> >>>         at
> org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run
> >>>
> >> (
> >>
> >>> ThreadPool.java:684)
> >>>         at java.lang.Thread.run(Thread.java:595)
> >>> 10:41:43,498 ERROR [[AxisServlet]] Servlet.service() for servlet
> >>> AxisServlet threw exception
> >>> java.lang.NullPointerException
> >>>         at
> >>>
> >>>
> >>
> org.apache.axis2.engine.AxisEngine.extractFaultInformationFromMessageContext
> >>
> >>> (AxisEngine.java:353)
> >>>         at
> org.apache.axis2.engine.AxisEngine.createFaultMessageContext(
> >>> AxisEngine.java:249)
> >>>         at org.apache.axis2.transport.http.AxisServlet.handleFault(
> >>> AxisServlet.java:317)
> >>>         at org.apache.axis2.transport.http.AxisServlet.doPost(
> >>> AxisServlet.java:277)
> >>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java
> :709)
> >>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java
> :802)
> >>>         at
> >>> org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(
> >>> ApplicationFilterChain.java:252)
> >>>         at org.apache.catalina.core.ApplicationFilterChain.doFilter(
> >>> ApplicationFilterChain.java:173)
> >>>         at org.apache.catalina.core.StandardWrapperValve.invoke(
> >>> StandardWrapperValve.java:213)
> >>>         at org.apache.catalina.core.StandardContextValve.invoke(
> >>> StandardContextValve.java:178)
> >>>         at org.apache.catalina.core.StandardHostValve.invoke(
> >>> StandardHostValve.java:126)
> >>>         at org.apache.catalina.valves.ErrorReportValve.invoke(
> >>> ErrorReportValve.java:105)
> >>>         at org.apache.catalina.core.StandardEngineValve.invoke(
> >>> StandardEngineValve.java:107)
> >>>         at org.apache.catalina.connector.CoyoteAdapter.service(
> >>> CoyoteAdapter.java:148)
> >>>         at org.apache.coyote.http11.Http11Processor.process(
> >>> Http11Processor.java:869)
> >>>         at
> >>>
> >>>
> >>
> org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection
> >>
> >>> (Http11BaseProtocol.java:664)
> >>>         at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(
> >>> PoolTcpEndpoint.java:527)
> >>>         at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt
> (
> >>> LeaderFollowerWorkerThread.java:80)
> >>>         at
> org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run
> >>>
> >> (
> >>
> >>> ThreadPool.java:684)
> >>>         at java.lang.Thread.run(Thread.java:595)
> >>> ERROR - GeronimoLog.error(108) | Servlet.service() for servlet jsp
> threw
> >>> exception
> >>> java.lang.IllegalStateException: getOutputStream() has already been
> >>>
> >> called
> >>
> >>> for this response
> >>>         at org.apache.catalina.connector.Response.getWriter(
> >>>
> >> Response.java
> >>
> >>> :599)
> >>>         at org.apache.catalina.connector.ResponseFacade.getWriter(
> >>> ResponseFacade.java:195)
> >>>         at org.apache.jasper.runtime.JspWriterImpl.initOut(
> >>> JspWriterImpl.java:124)
> >>>         at org.apache.jasper.runtime.JspWriterImpl.flushBuffer(
> >>> JspWriterImpl.java:117)
> >>>         at org.apache.jasper.runtime.PageContextImpl.release(
> >>> PageContextImpl.java:182)
> >>>         at
> >>> org.apache.jasper.runtime.JspFactoryImpl.internalReleasePageContext(
> >>> JspFactoryImpl.java:115)
> >>>         at org.apache.jasper.runtime.JspFactoryImpl.releasePageContext
> (
> >>> JspFactoryImpl.java:75)
> >>>         at org.apache.jsp.axis2_002dweb.Error.error500_jsp._jspService
> >>> (error500_jsp.java:97)
> >>>         at org.apache.jasper.runtime.HttpJspBase.service(
> >>>
> >> HttpJspBase.java
> >>
> >>> :97)
> >>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java
> :802)
> >>>         at org.apache.jasper.servlet.JspServletWrapper.service(
> >>> JspServletWrapper.java:334)
> >>>         at org.apache.jasper.servlet.JspServlet.serviceJspFile(
> >>> JspServlet.java:314)
> >>>         at org.apache.jasper.servlet.JspServlet.service(
> JspServlet.java
> >>> :264)
> >>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java
> :802)
> >>>         at
> >>> org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(
> >>> ApplicationFilterChain.java:252)
> >>>         at org.apache.catalina.core.ApplicationFilterChain.doFilter(
> >>> ApplicationFilterChain.java:173)
> >>>         at org.apache.catalina.core.ApplicationDispatcher.invoke(
> >>> ApplicationDispatcher.java:672)
> >>>         at
> org.apache.catalina.core.ApplicationDispatcher.processRequest
> >>>
> >> (
> >>
> >>> ApplicationDispatcher.java:465)
> >>>         at org.apache.catalina.core.ApplicationDispatcher.doForward(
> >>> ApplicationDispatcher.java:398)
> >>>         at org.apache.catalina.core.ApplicationDispatcher.forward(
> >>> ApplicationDispatcher.java:301)
> >>>         at org.apache.catalina.core.StandardHostValve.custom(
> >>> StandardHostValve.java:363)
> >>>         at org.apache.catalina.core.StandardHostValve.status(
> >>> StandardHostValve.java:284)
> >>>         at org.apache.catalina.core.StandardHostValve.throwable(
> >>> StandardHostValve.java:228)
> >>>         at org.apache.catalina.core.StandardHostValve.invoke(
> >>> StandardHostValve.java:134)
> >>>         at org.apache.catalina.valves.ErrorReportValve.invoke(
> >>> ErrorReportValve.java:105)
> >>>         at org.apache.catalina.core.StandardEngineValve.invoke(
> >>> StandardEngineValve.java:107)
> >>>         at org.apache.catalina.connector.CoyoteAdapter.service(
> >>> CoyoteAdapter.java:148)
> >>>         at org.apache.coyote.http11.Http11Processor.process(
> >>> Http11Processor.java:869)
> >>>         at
> >>>
> >>>
> >>
> org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection
> >>
> >>> (Http11BaseProtocol.java:664)
> >>>         at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(
> >>> PoolTcpEndpoint.java:527)
> >>>         at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt
> (
> >>> LeaderFollowerWorkerThread.java:80)
> >>>         at
> org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run
> >>>
> >> (
> >>
> >>> ThreadPool.java:684)
> >>>         at java.lang.Thread.run(Thread.java:595)
> >>> 10:41:44,369 ERROR [[jsp]] Servlet.service() for servlet jsp threw
> >>> exception
> >>> java.lang.IllegalStateException: getOutputStream() has already been
> >>>
> >> called
> >>
> >>> for this response
> >>>         at org.apache.catalina.connector.Response.getWriter(
> >>>
> >> Response.java
> >>
> >>> :599)
> >>>         at org.apache.catalina.connector.ResponseFacade.getWriter(
> >>> ResponseFacade.java:195)
> >>>         at org.apache.jasper.runtime.JspWriterImpl.initOut(
> >>> JspWriterImpl.java:124)
> >>>         at org.apache.jasper.runtime.JspWriterImpl.flushBuffer(
> >>> JspWriterImpl.java:117)
> >>>         at org.apache.jasper.runtime.PageContextImpl.release(
> >>> PageContextImpl.java:182)
> >>>         at
> >>> org.apache.jasper.runtime.JspFactoryImpl.internalReleasePageContext(
> >>> JspFactoryImpl.java:115)
> >>>         at org.apache.jasper.runtime.JspFactoryImpl.releasePageContext
> (
> >>> JspFactoryImpl.java:75)
> >>>         at org.apache.jsp.axis2_002dweb.Error.error500_jsp._jspService
> >>> (error500_jsp.java:97)
> >>>         at org.apache.jasper.runtime.HttpJspBase.service(
> >>>
> >> HttpJspBase.java
> >>
> >>> :97)
> >>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java
> :802)
> >>>         at org.apache.jasper.servlet.JspServletWrapper.service(
> >>> JspServletWrapper.java:334)
> >>>         at org.apache.jasper.servlet.JspServlet.serviceJspFile(
> >>> JspServlet.java:314)
> >>>         at org.apache.jasper.servlet.JspServlet.service(
> JspServlet.java
> >>> :264)
> >>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java
> :802)
> >>>         at
> >>> org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(
> >>> ApplicationFilterChain.java:252)
> >>>         at org.apache.catalina.core.ApplicationFilterChain.doFilter(
> >>> ApplicationFilterChain.java:173)
> >>>         at org.apache.catalina.core.ApplicationDispatcher.invoke(
> >>> ApplicationDispatcher.java:672)
> >>>         at
> org.apache.catalina.core.ApplicationDispatcher.processRequest
> >>>
> >> (
> >>
> >>> ApplicationDispatcher.java:465)
> >>>         at org.apache.catalina.core.ApplicationDispatcher.doForward(
> >>> ApplicationDispatcher.java:398)
> >>>         at org.apache.catalina.core.ApplicationDispatcher.forward(
> >>> ApplicationDispatcher.java:301)
> >>>         at org.apache.catalina.core.StandardHostValve.custom(
> >>> StandardHostValve.java:363)
> >>>         at org.apache.catalina.core.StandardHostValve.status(
> >>> StandardHostValve.java:284)
> >>>         at org.apache.catalina.core.StandardHostValve.throwable(
> >>> StandardHostValve.java:228)
> >>>         at org.apache.catalina.core.StandardHostValve.invoke(
> >>> StandardHostValve.java:134)
> >>>         at org.apache.catalina.valves.ErrorReportValve.invoke(
> >>> ErrorReportValve.java:105)
> >>>         at org.apache.catalina.core.StandardEngineValve.invoke(
> >>> StandardEngineValve.java:107)
> >>>         at org.apache.catalina.connector.CoyoteAdapter.service(
> >>> CoyoteAdapter.java:148)
> >>>         at org.apache.coyote.http11.Http11Processor.process(
> >>> Http11Processor.java:869)
> >>>         at
> >>>
> >>>
> >>
> org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection
> >>
> >>> (Http11BaseProtocol.java:664)
> >>>         at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(
> >>> PoolTcpEndpoint.java:527)
> >>>         at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt
> (
> >>> LeaderFollowerWorkerThread.java:80)
> >>>         at
> org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run
> >>>
> >> (
> >>
> >>> ThreadPool.java:684)
> >>>         at java.lang.Thread.run(Thread.java:595)
> >>>
> >>> -----Original Message-----
> >>> From: René Bos [mailto:r.bos@pagelink.nl]
> >>> Sent: woensdag 20 juni 2007 10:44
> >>> To: ode-user@incubator.apache.org
> >>> Subject: RE: Running Ode using an external database (MySql)
> >>>
> >>> Hi,
> >>>
> >>> Just did a new build from SVN, but the log looks the same for me!
> Maybe
> >>>
> >> I
> >>
> >>> overlooked something...
> >>>
> >>> I attached the lastest log.
> >>>
> >>> René
> >>>
> >>> -----Original Message-----
> >>> From: Matthieu Riou [mailto:matthieu.riou@gmail.com]
> >>> Sent: dinsdag 19 juni 2007 20:53
> >>> To: ode-user@incubator.apache.org
> >>> Subject: Re: Running Ode using an external database (MySql)
> >>>
> >>> Hi Rene,
> >>>
> >>> Sorry for the late reply, we're having some issues with bugs in Axis2
> >>> 1.1.1(and
> >>> 1.2 as well actually) that prevent seeing the original problem that
> >>>
> >> caused
> >>
> >>> a
> >>> fault. I've changed the build to use a patched version of axis2-kernel
> >>> that
> >>> solves this problems. So could you rebuild ODE from the trunk and try
> >>> again?
> >>> This way we'll know exactly what's wrong instead of trying to shoot in
> >>>
> >> the
> >>
> >>> dark.
> >>>
> >>> Thanks,
> >>> Matthieu
> >>>
> >>> On 6/18/07, René Bos <r.bos@pagelink.nl> wrote:
> >>>
> >>>> Hi,
> >>>>
> >>>>
> >>>>
> >>>> I just had time to work on the external (MySQL) database connection.
> >>>>
> >>>> As you said I made the ode-axis2.properties file with the following
> >>>> contents:
> >>>>
> >>>>
> >>>>
> >>>> ode-axis2.db.mode=EXTERNAL
> >>>>
> >>>> ode-axis2.db.ext.dataSource=java:comp/env/jdbc/OdeDB
> >>>>
> >>>>
> >>>>
> >>>> I placed a file mysql-connector-java-5.0.6-bin.jar in the
> >>>> <TOMCAT_INSTALL_DIR>\common\libs dir.
> >>>>
> >>>>
> >>>>
> >>>> In <TOMCAT_INSTALL_DIR>\conf\Catalina\localhost I added a file named
> >>>> ode.xml with the following contents:
> >>>>
> >>>>
> >>>>
> >>>> <Context path="/Ode" docBase="ode" debug="5"
> >>>> reloadable="true"                       crossContext="true">
> >>>>
> >>>>             <Resource name="jdbc/OdeDB"
> >>>>
> >>>>
> >>
> auth="Container"                                                                        type="
> >>
> >>>> javax.sql.DataSource"
> >>>>
> >>>>                         maxActive="100" maxIdle="30" maxWait="10000"
> >>>>
> >>>>                         username="username"
> >>>>
> >>>>
> >>
> password="password"                                                                  driverClassName="
> >>
> >>>> com.mysql.jdbc.Driver"
> >>>>
> >>>>
> >>>>
> >>> url="jdbc:mysql://localhost:3306/ode?autoReconnect=true"
> >>>
> >>>> validationQuery="select count(*) from ode_process"/>
> >>>>
> >>>> </Context>
> >>>>
> >>>>
> >>>>
> >>>> But with this I get the following:
> >>>>
> >>>>
> >>>>
> >>>> DEBUG - GeronimoLog.debug(66) | Invoking ODE using MEX
> >>>> {MyRoleMex#hqejbhcnphr2cxfe0gxg1s [Client hqejbhcnphr2cxfe0gxg1r]
> >>>>
> >>> calling {
> >>>
> >>>> http://OPM_modifyObject.wsdl}OPM_modifyObjectService.initiate(...)}
> >>>>
> >>>> 10:59:37,852 DEBUG [ODEService] Invoking ODE using MEX
> >>>> {MyRoleMex#hqejbhcnphr2cxfe0gxg1s [Client hqejbhcnphr2cxfe0gxg1r]
> >>>>
> >>> calling {
> >>>
> >>>> http://OPM_modifyObject.wsdl}OPM_modifyObjectService.initiate(...)}
> >>>>
> >>>> DEBUG - GeronimoLog.debug(66) | Message content:  <?xml version="1.0"
> >>>> encoding="UTF-8"?>
> >>>>
> >>>> <message><processInstanceID xmlns:ns1="http://OPM_modifyObject.wsdl"
> >>>> xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/
> >>>>
> >>> ">OPM_modifyObject-1182157173</processInstanceID><userID
> >>>
> >>>> xmlns:ns1="http://OPM_modifyObject.wsdl" xm
> >>>>
> >>>> k xmlns:ns1="http://OPM_modifyObject.wsdl" xmlns:soapenv="
> >>>> http://schemas.xmlsoap.org/soap/envelope/">Vet gaaf
> >>>> man</feedback></message>
> >>>>
> >>>> 10:59:37,852 DEBUG [ODEService] Message content:  <?xml version="1.0"
> >>>> encoding="UTF-8"?>
> >>>>
> >>>> <message><processInstanceID xmlns:ns1="http://OPM_modifyObject.wsdl"
> >>>> xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/
> >>>>
> >>> ">OPM_modifyObject-1182157173</processInstanceID><userID
> >>>
> >>>> xmlns:ns1="http://OPM_modifyObject.wsdl" xm
> >>>>
> >>>> k xmlns:ns1="http://OPM_modifyObject.wsdl" xmlns:soapenv="
> >>>> http://schemas.xmlsoap.org/soap/envelope/">Vet gaaf
> >>>> man</feedback></message>
> >>>>
> >>>> DEBUG - GeronimoLog.debug(66) | Routed: svcQname {
> >>>> http://OPM_modifyObject.wsdl}OPM_modifyObjectService -->
> BpelProcess[{
> >>>> http://OPM_modifyObject}OPM_modifyObject-4]
> >>>>
> >>>> 10:59:37,872 DEBUG [BpelEngineImpl] Routed: svcQname {
> >>>> http://OPM_modifyObject.wsdl}OPM_modifyObjectService -->
> BpelProcess[{
> >>>> http://OPM_modifyObject}OPM_modifyObject-4]
> >>>>
> >>>> DEBUG - GeronimoLog.debug(66) | invoke() EPR= null ==> BpelProcess[{
> >>>> http://OPM_modifyObject}OPM_modifyObject-4]
> >>>>
> >>>> 10:59:37,882 DEBUG [MyRoleMessageExchangeImpl] invoke() EPR= null ==>
> >>>> BpelProcess[{http://OPM_modifyObject}OPM_modifyObject-4]
> >>>>
> >>>> DEBUG - GeronimoLog.debug(66) | Commiting ODE MEX
> >>>> {MyRoleMex#hqejbhcnphr2cxfe0gxg1s [Client hqejbhcnphr2cxfe0gxg1r]
> >>>>
> >>> calling {
> >>>
> >>>> http://OPM_modifyObject.wsdl}OPM_modifyObjectService.initiate(...)}
> >>>>
> >>>> 10:59:38,073 DEBUG [ODEService] Commiting ODE MEX
> >>>> {MyRoleMex#hqejbhcnphr2cxfe0gxg1s [Client hqejbhcnphr2cxfe0gxg1r]
> >>>>
> >>> calling {
> >>>
> >>>> http://OPM_modifyObject.wsdl}OPM_modifyObjectService.initiate(...)}
> >>>>
> >>>> DEBUG - GeronimoLog.debug(66) | Commiting transaction.
> >>>>
> >>>> 10:59:38,073 DEBUG [ODEService] Commiting transaction.
> >>>>
> >>>> ERROR - GeronimoLog.error(104) | Timeout or execution error when
> >>>>
> >> waiting
> >>
> >>>> for response to MEX {MyRoleMex#hqejbhcnphr2cxfe0gxg1s [Client
> >>>> hqejbhcnphr2cxfe0gxg1r] calling {
> >>>> http://OPM_modifyObject.wsdl}OPM_modifyObjectService.init
> >>>>
> >>>>
> he.ode.bpel.engine.MyRoleMessageExchangeImpl$ResponseFuture@23d87ftimed
> >>>> out when waiting for a response!
> >>>>
> >>>> 11:01:38,516 ERROR [ODEService] Timeout or execution error when
> >>>>
> >> waiting
> >>
> >>>> for response to MEX {MyRoleMex#hqejbhcnphr2cxfe0gxg1s [Client
> >>>> hqejbhcnphr2cxfe0gxg1r] calling {
> >>>> http://OPM_modifyObject.wsdl}OPM_modifyObjectService.initi
> >>>>
> >>>>
> e.ode.bpel.engine.MyRoleMessageExchangeImpl$ResponseFuture@23d87ftimed
> >>>> out when waiting for a response!
> >>>>
> >>>> ERROR - GeronimoLog.error(108) | Servlet.service() for servlet
> >>>>
> >>> AxisServlet
> >>>
> >>>> threw exception
> >>>>
> >>>> java.lang.NullPointerException
> >>>>
> >>>>         at
> >>>>
> >>>>
> >>
> org.apache.axis2.engine.AxisEngine.extractFaultInformationFromMessageContext
> >>
> >>>> (AxisEngine.java:353)
> >>>>
> >>>>         at
> >>>>
> >> org.apache.axis2.engine.AxisEngine.createFaultMessageContext(
> >>
> >>>> AxisEngine.java:249)
> >>>>
> >>>>         at org.apache.axis2.transport.http.AxisServlet.handleFault(
> >>>> AxisServlet.java:317)
> >>>>
> >>>>         at org.apache.axis2.transport.http.AxisServlet.doPost(
> >>>> AxisServlet.java:277)
> >>>>
> >>>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java
> >>>>
> >> :709)
> >>
> >>>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java
> >>>>
> >> :802)
> >>
> >>>>         at
> >>>> org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(
> >>>> ApplicationFilterChain.java:252)
> >>>>
> >>>>         at org.apache.catalina.core.ApplicationFilterChain.doFilter(
> >>>> ApplicationFilterChain.java:173)
> >>>>
> >>>>         at org.apache.catalina.core.StandardWrapperValve.invoke(
> >>>> StandardWrapperValve.java:213)
> >>>>
> >>>>         at org.apache.catalina.core.StandardContextValve.invoke(
> >>>> StandardContextValve.java:178)
> >>>>
> >>>>         at org.apache.catalina.core.StandardHostValve.invoke(
> >>>> StandardHostValve.java:126)
> >>>>
> >>>>         at org.apache.catalina.valves.ErrorReportValve.invoke(
> >>>> ErrorReportValve.java:105)
> >>>>
> >>>>         at org.apache.catalina.core.StandardEngineValve.invoke(
> >>>> StandardEngineValve.java:107)
> >>>>
> >>>>         at org.apache.catalina.connector.CoyoteAdapter.service(
> >>>> CoyoteAdapter.java:148)
> >>>>
> >>>>         at org.apache.coyote.http11.Http11Processor.process(
> >>>> Http11Processor.java:869)
> >>>>
> >>>>         at
> >>>>
> >>>>
> >>
> org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection
> >>
> >>>> (Http11BaseProtocol.java:664)
> >>>>
> >>>>         at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(
> >>>> PoolTcpEndpoint.java:527)
> >>>>
> >>>>         at
> org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt
> >>>>
> >> (
> >>
> >>>> LeaderFollowerWorkerThread.java:80)
> >>>>
> >>>>         at
> >>>>
> >> org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run
> >>
> >>> (
> >>>
> >>>> ThreadPool.java:684)
> >>>>
> >>>>         at java.lang.Thread.run(Thread.java:595)
> >>>>
> >>>> 11:01:38,626 ERROR [[AxisServlet]] Servlet.service() for servlet
> >>>> AxisServlet threw exception
> >>>>
> >>>> java.lang.NullPointerException
> >>>>
> >>>>         at
> >>>>
> >>>>
> >>
> org.apache.axis2.engine.AxisEngine.extractFaultInformationFromMessageContext
> >>
> >>>> (AxisEngine.java:353)
> >>>>
> >>>>         at
> >>>>
> >> org.apache.axis2.engine.AxisEngine.createFaultMessageContext(
> >>
> >>>> AxisEngine.java:249)
> >>>>
> >>>>         at org.apache.axis2.transport.http.AxisServlet.handleFault(
> >>>> AxisServlet.java:317)
> >>>>
> >>>>         at org.apache.axis2.transport.http.AxisServlet.doPost(
> >>>> AxisServlet.java:277)
> >>>>
> >>>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java
> >>>>
> >> :709)
> >>
> >>>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java
> >>>>
> >> :802)
> >>
> >>>>         at
> >>>> org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(
> >>>> ApplicationFilterChain.java:252)
> >>>>
> >>>>         at org.apache.catalina.core.ApplicationFilterChain.doFilter(
> >>>> ApplicationFilterChain.java:173)
> >>>>
> >>>>         at org.apache.catalina.core.StandardWrapperValve.invoke(
> >>>> StandardWrapperValve.java:213)
> >>>>
> >>>>         at org.apache.catalina.core.StandardContextValve.invoke(
> >>>> StandardContextValve.java:178)
> >>>>
> >>>>         at org.apache.catalina.core.StandardHostValve.invoke(
> >>>> StandardHostValve.java:126)
> >>>>
> >>>>         at org.apache.catalina.valves.ErrorReportValve.invoke(
> >>>> ErrorReportValve.java:105)
> >>>>
> >>>>         at org.apache.catalina.core.StandardEngineValve.invoke(
> >>>> StandardEngineValve.java:107)
> >>>>
> >>>>         at org.apache.catalina.connector.CoyoteAdapter.service(
> >>>> CoyoteAdapter.java:148)
> >>>>
> >>>>         at org.apache.coyote.http11.Http11Processor.process(
> >>>> Http11Processor.java:869)
> >>>>
> >>>>         at
> >>>>
> >>>>
> >>
> org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection
> >>
> >>>> (Http11BaseProtocol.java:664)
> >>>>
> >>>>         at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(
> >>>> PoolTcpEndpoint.java:527)
> >>>>
> >>>>         at
> org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt
> >>>>
> >> (
> >>
> >>>> LeaderFollowerWorkerThread.java:80)
> >>>>
> >>>>         at
> >>>>
> >> org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run
> >>
> >>> (
> >>>
> >>>> ThreadPool.java:684)
> >>>>
> >>>>         at java.lang.Thread.run(Thread.java:595)
> >>>>
> >>>> ERROR - GeronimoLog.error(108) | Servlet.service() for servlet jsp
> >>>>
> >> threw
> >>
> >>>> exception
> >>>>
> >>>> java.lang.IllegalStateException: getOutputStream() has already been
> >>>>
> >>> called
> >>>
> >>>> for this response
> >>>>
> >>>>         at org.apache.catalina.connector.Response.getWriter(
> >>>>
> >>> Response.java
> >>>
> >>>> :599)
> >>>>
> >>>>         at org.apache.catalina.connector.ResponseFacade.getWriter(
> >>>> ResponseFacade.java:195)
> >>>>
> >>>>         at org.apache.jasper.runtime.JspWriterImpl.initOut(
> >>>> JspWriterImpl.java:124)
> >>>>
> >>>>         at org.apache.jasper.runtime.JspWriterImpl.flushBuffer(
> >>>> JspWriterImpl.java:117)
> >>>>
> >>>>         at org.apache.jasper.runtime.PageContextImpl.release(
> >>>> PageContextImpl.java:182)
> >>>>
> >>>>         at
> >>>> org.apache.jasper.runtime.JspFactoryImpl.internalReleasePageContext(
> >>>> JspFactoryImpl.java:115)
> >>>>
> >>>>         at
> org.apache.jasper.runtime.JspFactoryImpl.releasePageContext
> >>>>
> >> (
> >>
> >>>> JspFactoryImpl.java:75)
> >>>>
> >>>>         at
> org.apache.jsp.axis2_002dweb.Error.error500_jsp._jspService
> >>>> (error500_jsp.java:97)
> >>>>
> >>>>         at org.apache.jasper.runtime.HttpJspBase.service(
> >>>>
> >>> HttpJspBase.java
> >>>
> >>>> :97)
> >>>>
> >>>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java
> >>>>
> >> :802)
> >>
> >>>>         at org.apache.jasper.servlet.JspServletWrapper.service(
> >>>> JspServletWrapper.java:334)
> >>>>
> >>>>         at org.apache.jasper.servlet.JspServlet.serviceJspFile(
> >>>> JspServlet.java:314)
> >>>>
> >>>>         at org.apache.jasper.servlet.JspServlet.service(
> >>>>
> >> JspServlet.java
> >>
> >>>> :264)
> >>>>
> >>>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java
> >>>>
> >> :802)
> >>
> >>>>         at
> >>>> org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(
> >>>> ApplicationFilterChain.java:252)
> >>>>
> >>>>         at org.apache.catalina.core.ApplicationFilterChain.doFilter(
> >>>> ApplicationFilterChain.java:173)
> >>>>
> >>>>         at org.apache.catalina.core.ApplicationDispatcher.invoke(
> >>>> ApplicationDispatcher.java:672)
> >>>>
> >>>>         at
> >>>>
> >> org.apache.catalina.core.ApplicationDispatcher.processRequest
> >>
> >>> (
> >>>
> >>>> ApplicationDispatcher.java:465)
> >>>>
> >>>>         at org.apache.catalina.core.ApplicationDispatcher.doForward(
> >>>> ApplicationDispatcher.java:398)
> >>>>
> >>>>         at org.apache.catalina.core.ApplicationDispatcher.forward(
> >>>> ApplicationDispatcher.java:301)
> >>>>
> >>>>         at org.apache.catalina.core.StandardHostValve.custom(
> >>>> StandardHostValve.java:363)
> >>>>
> >>>>         at org.apache.catalina.core.StandardHostValve.status(
> >>>> StandardHostValve.java:284)
> >>>>
> >>>>         at org.apache.catalina.core.StandardHostValve.throwable(
> >>>> StandardHostValve.java:228)
> >>>>
> >>>>         at org.apache.catalina.core.StandardHostValve.invoke(
> >>>> StandardHostValve.java:134)
> >>>>
> >>>>         at org.apache.catalina.valves.ErrorReportValve.invoke(
> >>>> ErrorReportValve.java:105)
> >>>>
> >>>>         at org.apache.catalina.core.StandardEngineValve.invoke(
> >>>> StandardEngineValve.java:107)
> >>>>
> >>>>         at org.apache.catalina.connector.CoyoteAdapter.service(
> >>>> CoyoteAdapter.java:148)
> >>>>
> >>>>         at org.apache.coyote.http11.Http11Processor.process(
> >>>> Http11Processor.java:869)
> >>>>
> >>>>         at
> >>>>
> >>>>
> >>
> org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection
> >>
> >>>> (Http11BaseProtocol.java:664)
> >>>>
> >>>>         at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(
> >>>> PoolTcpEndpoint.java:527)
> >>>>
> >>>>         at
> org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt
> >>>>
> >> (
> >>
> >>>> LeaderFollowerWorkerThread.java:80)
> >>>>
> >>>>         at
> >>>>
> >> org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run
> >>
> >>> (
> >>>
> >>>> ThreadPool.java:684)
> >>>>
> >>>>         at java.lang.Thread.run(Thread.java:595)
> >>>>
> >>>> 11:01:39,307 ERROR [[jsp]] Servlet.service() for servlet jsp threw
> >>>> exception
> >>>>
> >>>> java.lang.IllegalStateException: getOutputStream() has already been
> >>>>
> >>> called
> >>>
> >>>> for this response
> >>>>
> >>>>         at org.apache.catalina.connector.Response.getWriter(
> >>>>
> >>> Response.java
> >>>
> >>>> :599)
> >>>>
> >>>>         at org.apache.catalina.connector.ResponseFacade.getWriter(
> >>>> ResponseFacade.java:195)
> >>>>
> >>>>         at org.apache.jasper.runtime.JspWriterImpl.initOut(
> >>>> JspWriterImpl.java:124)
> >>>>
> >>>>         at org.apache.jasper.runtime.JspWriterImpl.flushBuffer(
> >>>> JspWriterImpl.java:117)
> >>>>
> >>>>         at org.apache.jasper.runtime.PageContextImpl.release(
> >>>> PageContextImpl.java:182)
> >>>>
> >>>>         at
> >>>> org.apache.jasper.runtime.JspFactoryImpl.internalReleasePageContext(
> >>>> JspFactoryImpl.java:115)
> >>>>
> >>>>         at
> org.apache.jasper.runtime.JspFactoryImpl.releasePageContext
> >>>>
> >> (
> >>
> >>>> JspFactoryImpl.java:75)
> >>>>
> >>>>         at
> org.apache.jsp.axis2_002dweb.Error.error500_jsp._jspService
> >>>> (error500_jsp.java:97)
> >>>>
> >>>>         at org.apache.jasper.runtime.HttpJspBase.service(
> >>>>
> >>> HttpJspBase.java
> >>>
> >>>> :97)
> >>>>
> >>>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java
> >>>>
> >> :802)
> >>
> >>>>         at org.apache.jasper.servlet.JspServletWrapper.service(
> >>>> JspServletWrapper.java:334)
> >>>>
> >>>>         at org.apache.jasper.servlet.JspServlet.serviceJspFile(
> >>>> JspServlet.java:314)
> >>>>
> >>>>         at org.apache.jasper.servlet.JspServlet.service(
> >>>>
> >> JspServlet.java
> >>
> >>>> :264)
> >>>>
> >>>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java
> >>>>
> >> :802)
> >>
> >>>>         at
> >>>> org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(
> >>>> ApplicationFilterChain.java:252)
> >>>>
> >>>>         at org.apache.catalina.core.ApplicationFilterChain.doFilter(
> >>>> ApplicationFilterChain.java:173)
> >>>>
> >>>>         at org.apache.catalina.core.ApplicationDispatcher.invoke(
> >>>> ApplicationDispatcher.java:672)
> >>>>
> >>>>         at
> >>>>
> >> org.apache.catalina.core.ApplicationDispatcher.processRequest
> >>
> >>> (
> >>>
> >>>> ApplicationDispatcher.java:465)
> >>>>
> >>>>         at org.apache.catalina.core.ApplicationDispatcher.doForward(
> >>>> ApplicationDispatcher.java:398)
> >>>>
> >>>>         at org.apache.catalina.core.ApplicationDispatcher.forward(
> >>>> ApplicationDispatcher.java:301)
> >>>>
> >>>>         at org.apache.catalina.core.StandardHostValve.custom(
> >>>> StandardHostValve.java:363)
> >>>>
> >>>>         at org.apache.catalina.core.StandardHostValve.status(
> >>>> StandardHostValve.java:284)
> >>>>
> >>>>         at org.apache.catalina.core.StandardHostValve.throwable(
> >>>> StandardHostValve.java:228)
> >>>>
> >>>>         at org.apache.catalina.core.StandardHostValve.invoke(
> >>>> StandardHostValve.java:134)
> >>>>
> >>>>         at org.apache.catalina.valves.ErrorReportValve.invoke(
> >>>> ErrorReportValve.java:105)
> >>>>
> >>>>         at org.apache.catalina.core.StandardEngineValve.invoke(
> >>>> StandardEngineValve.java:107)
> >>>>
> >>>>         at org.apache.catalina.connector.CoyoteAdapter.service(
> >>>> CoyoteAdapter.java:148)
> >>>>
> >>>>         at org.apache.coyote.http11.Http11Processor.process(
> >>>> Http11Processor.java:869)
> >>>>
> >>>>         at
> >>>>
> >>>>
> >>
> org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection
> >>
> >>>> (Http11BaseProtocol.java:664)
> >>>>
> >>>>         at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(
> >>>> PoolTcpEndpoint.java:527)
> >>>>
> >>>>         at
> org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt
> >>>>
> >> (
> >>
> >>>> LeaderFollowerWorkerThread.java:80)
> >>>>
> >>>>         at
> >>>>
> >> org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run
> >>
> >>> (
> >>>
> >>>> ThreadPool.java:684)
> >>>>
> >>>>         at java.lang.Thread.run(Thread.java:595)
> >>>>
> >>>>
> >>>>
> >>>> The process I'm using worked fine with the internal database. The
> >>>> deployment of the process was also OK. It was added to the external
> >>>>
> >>> database
> >>>
> >>>> in the bpel_process table.
> >>>>
> >>>>
> >>>>
> >>>> In <TOMCAT_INSTALL_DIR>\webapps\ode\WEB_INF\web.xml I found the
> >>>>
> >>> following
> >>>
> >>>> lines. As the say I uncommented them to for testing, but the  same
> >>>>
> >> error
> >>
> >>> as
> >>>
> >>>> above.
> >>>>
> >>>> <!-- UNCOMMENT TO USE APPSERVER DATASOURCE -->
> >>>>
> >>>> <resource-ref>
> >>>>
> >>>>             <res-ref-name>jdbc/OdeDB</res-ref-name>
> >>>>
> >>>>             <res-type>javax.sql.DataSource</res-type>
> >>>>
> >>>>             <res-auth>Container</res-auth>
> >>>>
> >>>>             <res-sharing-scope>Shareable</res-sharing-scope>
> >>>>
> >>>> </resource-ref>
> >>>>
> >>>> <!--  UNCOMMENT TO USE APPSERVER DATASOURCE -->
> >>>>
> >>>>
> >>>>
> >>>> What can be wrong? I used the mysql.sql you provided.
> >>>>
> >>>>
> >>>>
> >>>> Thanks!
> >>>>
> >>>>             Rene
> >>>>
> >>>>
> >>>>
> >>>> ________________________________
> >>>>
> >>>> From: Matthieu Riou [mailto:matthieu.riou@gmail.com]
> >>>> Sent: dinsdag 12 juni 2007 16:54
> >>>> To: ode-user@incubator.apache.org
> >>>> Subject: Re: Running Ode using an external database (MySql)
> >>>>
> >>>>
> >>>>
> >>>> Hi Michael,
> >>>>
> >>>> I've just cleaned that up a bit, there were a couple of issues there.
> >>>>
> >> So
> >>
> >>>> to build the MySQL schema you just need to go into the
> >>>>
> >>> dao-jpa-ojpa-derby
> >>>
> >>>> module and run 'rake build'. This should produce a schema for MySQL
> in
> >>>>
> >>> the
> >>>
> >>>> target directory. Alternatively I've attached the result to this
> >>>>
> >> e-mail
> >>
> >>> if
> >>>
> >>>> it's easier for you.
> >>>>
> >>>> Once MySQL is setup, you just need to configure ODE to use the
> >>>>
> >>> datasource
> >>>
> >>>> you've configured in your application server. Just edit
> >>>> ode-axis2.properties and set the 2 following properties:
> >>>>
> >>>> ode-axis2.db.mode=EXTERNAL
> >>>> ode-axis2.db.ext.dataSource=java:comp/env/jdbc/MYDS
> >>>>
> >>>> That should be it. If you can write down the different steps you
> >>>>
> >>> followed
> >>>
> >>>> to make this work with your application server, we'd be more than
> >>>>
> >> happy
> >>
> >>> to
> >>>
> >>>> publish it as a small "how to" so others can reuse it :)
> >>>>
> >>>> Thanks,
> >>>> Matthieu
> >>>>
> >>>> On 6/12/07, Michael Horwitz <mike.horwitz@gmail.com> wrote:
> >>>>
> >>>> Hi,
> >>>>
> >>>> There are various cryptic hints around the site that Ode can be run
> >>>> against
> >>>> an external database. I have been experiencing a few problems with
> the
> >>>> Quartz scheduler not being able to get a connection to the embedded
> >>>> database
> >>>> when running asynchronous processes and would like to try running
> >>>>
> >>> against
> >>>
> >>>> MySql to see if it will solve my problems. Are there any scripts
> >>>>
> >>> available
> >>>
> >>>> to create the database?
> >>>>
> >>>> Thanks
> >>>>
> >>>> Mike Horwitz
> >>>>
> >>>>
> >>>>
> >>>>
> >>>>
>

Mime
View raw message