[JBoss jBPM] - Re: Timer Problem - jboss-user

Monday, 29 January 2007

Here is a log snippet as well:

  | 2007-01-27 14:16:00,433 INFO  [com.spss.batch.queue.BatchJobQueue] Getting jBPM
context..
  | 2007-01-27 14:16:00,433 DEBUG [org.jbpm.configuration.JbpmContextInfo] creating jbpm
context with service factories '[message, scheduler, logging, persistence,
authentication]'
  | 2007-01-27 14:16:00,433 DEBUG [org.jbpm.JbpmContext] creating JbpmContext
  | 2007-01-27 14:16:00,433 INFO  [com.spss.batch.queue.BatchJobQueue] Getting Graph
Session..
  | 2007-01-27 14:16:00,433 DEBUG [org.jbpm.persistence.db.DbPersistenceServiceFactory]
creating persistence service
  | 2007-01-27 14:16:00,433 DEBUG [org.jbpm.persistence.db.DbPersistenceService] creating
hibernate session
  | 2007-01-27 14:16:00,448 DEBUG [org.hibernate.impl.SessionImpl] opened session at
timestamp: 4792014276395008
  | 2007-01-27 14:16:00,448 DEBUG [org.jbpm.persistence.db.DbPersistenceService] beginning
hibernate transaction
  | 2007-01-27 14:16:00,448 DEBUG [org.hibernate.transaction.JTATransaction] Looking for
UserTransaction under: UserTransaction
  | 2007-01-27 14:16:00,448 DEBUG [org.hibernate.transaction.JTATransaction] Obtained
UserTransaction
  | 2007-01-27 14:16:00,448 DEBUG [org.hibernate.transaction.JTATransaction] begin
  | 2007-01-27 14:16:00,448 DEBUG [org.hibernate.jdbc.JDBCContext] successfully registered
Synchronization
  | 2007-01-27 14:16:00,448 INFO  [com.spss.batch.queue.BatchJobQueue] Looking up process
definition...
  | 2007-01-27 14:16:00,448 INFO  [com.spss.batch.queue.BatchJobQueue] Creating process
definition...
  | 2007-01-27 14:16:00,448 DEBUG [org.jbpm.jpdl.xml.JpdlParser] resolving schema
reference publicId(null) systemId(http://jbpm.org/jpdl-3.1.xsd)
  | 2007-01-27 14:16:00,448 DEBUG [org.jbpm.jpdl.xml.JpdlParser] providing input source to
local 'jpdl-3.1.xsd' resource
  | 2007-01-27 14:16:00,495 WARN  [org.jbpm.jpdl.xml.JpdlXmlReader] process xml warning:
action does not have class nor ref-name attribute <action
xmlns="urn:jbpm.org:jpdl-3.1" name="doTask"
config-type="field" accept-propagated-events="true"/>
  | 2007-01-27 14:16:00,495 INFO  [com.spss.batch.queue.BatchJobQueue] Saving process
definition...
  | 2007-01-27 14:16:00,495 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:00,495 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:00,495 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,495 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,526 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 75
  | 2007-01-27 14:16:00,542 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:00,542 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:00,542 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:00,542 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 75, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:00,542 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:00,542 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:00,542 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,542 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,558 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 76
  | 2007-01-27 14:16:00,573 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:00,573 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:00,573 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:00,573 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 76, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:00,573 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:00,573 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:00,573 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,573 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,604 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 77
  | 2007-01-27 14:16:00,620 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:00,620 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:00,620 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:00,620 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 77, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:00,620 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:00,620 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:00,620 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,620 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,636 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 78
  | 2007-01-27 14:16:00,667 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:00,667 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:00,667 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:00,667 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 78, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:00,667 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:00,667 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:00,667 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,667 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,683 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 79
  | 2007-01-27 14:16:00,698 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:00,698 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:00,698 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:00,698 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 79, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:00,698 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:00,698 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:00,698 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,698 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,714 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 80
  | 2007-01-27 14:16:00,745 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:00,745 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:00,745 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:00,745 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 80, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:00,745 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:00,745 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:00,745 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,745 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,776 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 81
  | 2007-01-27 14:16:00,792 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:00,792 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:00,792 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:00,792 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 81, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:00,808 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:00,808 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:00,808 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,808 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,823 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 82
  | 2007-01-27 14:16:00,839 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:00,839 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:00,839 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:00,839 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 82, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:00,839 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:00,839 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:00,839 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,839 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,854 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 83
  | 2007-01-27 14:16:00,886 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:00,886 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:00,886 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:00,886 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 83, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:00,886 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:00,886 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:00,886 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,886 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,901 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 84
  | 2007-01-27 14:16:00,917 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:00,917 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:00,917 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:00,917 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 84, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:00,917 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:00,917 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:00,917 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,917 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,948 DEBUG [org.jbpm.configuration.JbpmContextInfo] creating jbpm
context with service factories '[message, scheduler, logging, persistence,
authentication]'
  | 2007-01-27 14:16:00,948 DEBUG [org.jbpm.JbpmContext] creating JbpmContext
  | 2007-01-27 14:16:00,948 DEBUG [org.jbpm.persistence.db.DbPersistenceServiceFactory]
creating persistence service
  | 2007-01-27 14:16:00,948 DEBUG [org.jbpm.persistence.db.DbPersistenceService] creating
hibernate session
  | 2007-01-27 14:16:00,948 DEBUG [org.hibernate.impl.SessionImpl] opened session at
timestamp: 4792014278443008
  | 2007-01-27 14:16:00,948 DEBUG [org.jbpm.persistence.db.DbPersistenceService] beginning
hibernate transaction
  | 2007-01-27 14:16:00,948 DEBUG [org.hibernate.transaction.JTATransaction] Looking for
UserTransaction under: UserTransaction
  | 2007-01-27 14:16:00,948 DEBUG [org.hibernate.transaction.JTATransaction] Obtained
UserTransaction
  | 2007-01-27 14:16:00,948 DEBUG [org.hibernate.transaction.JTATransaction] begin
  | 2007-01-27 14:16:00,948 DEBUG [org.hibernate.transaction.JTATransaction] Began a new
JTA transaction
  | 2007-01-27 14:16:00,948 DEBUG [org.hibernate.jdbc.JDBCContext] successfully registered
Synchronization
  | 2007-01-27 14:16:00,948 DEBUG [org.jbpm.scheduler.impl.SchedulerThread] checking for
timers
  | 2007-01-27 14:16:00,948 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 1)
  | 2007-01-27 14:16:00,948 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:00,948 DEBUG [org.hibernate.SQL] select timer0_.ID_ as col_0_0_ from
abom_own.JBPM_TIMER timer0_ where (timer0_.EXCEPTION_ is null) and
timer0_.ISSUSPENDED_<>1 order by timer0_.DUEDATE_ asc
  | 2007-01-27 14:16:00,948 INFO  [STDOUT] Hibernate: select timer0_.ID_ as col_0_0_ from
abom_own.JBPM_TIMER timer0_ where (timer0_.EXCEPTION_ is null) and
timer0_.ISSUSPENDED_<>1 order by timer0_.DUEDATE_ asc
  | 2007-01-27 14:16:00,948 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 85
  | 2007-01-27 14:16:00,964 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 2)
  | 2007-01-27 14:16:00,964 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:00,964 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 1) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:00,964 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 85, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:00,964 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
ResultSet (open ResultSets: 0, globally: 0)
  | 2007-01-27 14:16:00,964 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 1)
  | 2007-01-27 14:16:00,964 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:00,964 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,964 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:00,980 DEBUG [org.hibernate.impl.IteratorImpl] exhausted results
  | 2007-01-27 14:16:00,980 DEBUG [org.hibernate.impl.IteratorImpl] closing iterator
  | 2007-01-27 14:16:00,980 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
ResultSet (open ResultSets: 1, globally: 1)
  | 2007-01-27 14:16:00,980 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 2)
  | 2007-01-27 14:16:00,980 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:00,980 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 1) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:00,980 DEBUG [org.jbpm.JbpmContext] closing JbpmContext
  | 2007-01-27 14:16:00,980 DEBUG [org.jbpm.svc.Services] closing service
'persistence': org.jbpm.persistence.db.DbPersistenceService@201a5c
  | 2007-01-27 14:16:00,980 DEBUG [org.jbpm.persistence.db.DbPersistenceService]
committing hibernate transaction
  | 2007-01-27 14:16:00,980 DEBUG [org.hibernate.transaction.JTATransaction] commit
  | 2007-01-27 14:16:00,980 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 86
  | 2007-01-27 14:16:00,995 DEBUG [org.hibernate.transaction.JTATransaction] Committed JTA
UserTransaction
  | 2007-01-27 14:16:00,995 DEBUG [org.jbpm.persistence.db.DbPersistenceService] closing
hibernate session
  | 2007-01-27 14:16:00,995 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:00,995 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:00,995 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:00,995 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 86, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,011 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,011 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,011 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,011 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,026 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 87
  | 2007-01-27 14:16:01,042 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,042 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,042 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,042 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 87, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,042 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,042 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,042 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,042 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,058 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 88
  | 2007-01-27 14:16:01,089 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,089 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,089 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,089 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 88, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,089 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,089 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,089 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,089 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,105 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 89
  | 2007-01-27 14:16:01,120 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,120 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,120 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,120 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 89, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,120 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,120 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,120 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,120 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,151 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 90
  | 2007-01-27 14:16:01,167 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,167 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,167 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,167 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 90, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,167 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,167 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,167 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,167 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,183 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 91
  | 2007-01-27 14:16:01,214 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,214 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,214 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,214 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 91, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,214 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,214 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,214 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,214 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,230 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 92
  | 2007-01-27 14:16:01,245 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,245 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,245 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,245 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 92, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,245 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,245 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,245 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,245 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,276 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 93
  | 2007-01-27 14:16:01,292 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,292 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,292 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,292 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 93, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,292 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,292 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,292 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,292 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,308 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 94
  | 2007-01-27 14:16:01,323 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,323 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,323 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,323 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 94, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,339 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,339 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,339 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,339 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,355 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 95
  | 2007-01-27 14:16:01,370 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,370 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,370 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,370 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 95, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,370 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,370 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,370 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,370 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,386 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 96
  | 2007-01-27 14:16:01,417 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,417 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,417 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,417 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 96, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,417 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,417 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,417 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,417 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,433 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 97
  | 2007-01-27 14:16:01,448 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,448 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,448 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,448 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 97, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,448 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,448 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,448 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,448 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,480 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 98
  | 2007-01-27 14:16:01,495 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,495 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,495 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,495 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 98, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,495 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,495 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,495 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,495 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,511 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 99
  | 2007-01-27 14:16:01,542 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,542 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,542 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,542 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 99, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,542 INFO  [com.spss.batch.queue.BatchJobQueue] Creating process
instance...
  | 2007-01-27 14:16:01,542 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,542 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,542 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,542 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,558 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 100
  | 2007-01-27 14:16:01,573 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,573 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,573 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,573 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 100, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,573 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,573 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,573 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,573 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,605 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 101
  | 2007-01-27 14:16:01,620 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,620 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,620 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,620 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 101, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,620 DEBUG [org.jbpm.graph.def.GraphElement] event
'process-start' on 'ProcessDefinition(FileEvent)' for 'Token(/)'
  | 2007-01-27 14:16:01,620 DEBUG [org.jbpm.context.exe.VariableContainer] create variable
'jobTask' in 'TokenVariableMap10c6402' with value 'JobTask:
file=C:\FileTest\test Watch File: C:\FileTest\testfile'
  | 2007-01-27 14:16:01,620 INFO  [com.spss.batch.queue.BatchJobQueue] Saving process
instance..
  | 2007-01-27 14:16:01,620 DEBUG [org.jbpm.svc.Services] executing default save
operations
  | 2007-01-27 14:16:01,620 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving
process instance
  | 2007-01-27 14:16:01,620 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to
logging service.
  | 2007-01-27 14:16:01,620 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,620 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,620 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,636 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,652 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 102
  | 2007-01-27 14:16:01,667 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,667 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,667 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,667 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 102, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,667 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,667 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,667 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,667 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,683 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 103
  | 2007-01-27 14:16:01,714 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,714 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,714 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,714 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 103, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,714 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,714 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,714 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,714 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,730 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 104
  | 2007-01-27 14:16:01,745 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,745 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,745 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,745 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 104, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,745 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,745 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,745 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,745 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,777 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 105
  | 2007-01-27 14:16:01,792 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,792 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,792 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,792 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 105, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,792 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,792 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,792 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,792 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,808 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 106
  | 2007-01-27 14:16:01,823 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,823 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,823 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,823 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 106, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,823 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,823 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,823 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,823 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,855 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 107
  | 2007-01-27 14:16:01,870 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,870 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,870 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,870 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 107, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,870 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save
of 'org.jbpm.graph.exe.ProcessInstance@13acec3'
  | 2007-01-27 14:16:01,870 INFO  [com.spss.batch.queue.BatchJobQueue] Closing context..
  | 2007-01-27 14:16:01,870 DEBUG [org.jbpm.JbpmContext] closing JbpmContext
  | 2007-01-27 14:16:01,870 DEBUG [org.jbpm.svc.Services] closing service
'persistence': org.jbpm.persistence.db.DbPersistenceService@10b8624
  | 2007-01-27 14:16:01,870 DEBUG [org.jbpm.persistence.db.DbPersistenceService]
committing hibernate transaction
  | 2007-01-27 14:16:01,870 DEBUG [org.hibernate.transaction.JTATransaction] commit
  | 2007-01-27 14:16:01,870 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener]
processing flush-time cascades
  | 2007-01-27 14:16:01,870 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,870 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,870 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,870 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,886 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 108
  | 2007-01-27 14:16:01,917 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,917 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,917 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,917 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 108, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,917 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,917 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,917 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,917 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,933 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 109
  | 2007-01-27 14:16:01,948 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,948 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,948 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,948 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 109, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,948 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:01,948 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:01,948 DEBUG [org.hibernate.SQL] select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,948 INFO  [STDOUT] Hibernate: select
abom_own.hibernate_sequence.nextval from dual
  | 2007-01-27 14:16:01,964 DEBUG [org.hibernate.id.SequenceGenerator] Sequence identifier
generated: 110
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively
releasing JDBC connection
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC
connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.event.def.AbstractSaveEventListener]
generated identifier: 110, using strategy: org.hibernate.id.SequenceGenerator
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener]
dirty checking collections
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.ProcessDefinition.nodes#75], was: [<unreferenced>]
(initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.ProcessDefinition.actions#75], was: [<unreferenced>]
(initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.ProcessDefinition.definitions#75], was: [<unreferenced>]
(initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.Node.leavingTransitions#76], was: [<unreferenced>]
(initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.Node.leavingTransitions#78], was: [<unreferenced>]
(initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.Node.arrivingTransitions#78], was: [<unreferenced>]
(initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.Node.events#82], was: [<unreferenced>] (initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.Node.leavingTransitions#82], was: [<unreferenced>]
(initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.Node.arrivingTransitions#82], was: [<unreferenced>]
(initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.Event.actions#83], was: [<unreferenced>] (initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.Event.actions#85], was: [<unreferenced>] (initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.Node.events#90], was: [<unreferenced>] (initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.Node.leavingTransitions#90], was: [<unreferenced>]
(initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.Node.arrivingTransitions#90], was: [<unreferenced>]
(initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.Event.actions#91], was: [<unreferenced>] (initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.Node.events#95], was: [<unreferenced>] (initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.Node.arrivingTransitions#95], was: [<unreferenced>]
(initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.def.Event.actions#96], was: [<unreferenced>] (initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.graph.exe.ProcessInstance.instances#100], was: [<unreferenced>]
(initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.bytes.ByteArray.byteBlocks#105], was: [<unreferenced>] (initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.bytes.ByteArray.byteBlocks#107], was: [<unreferenced>] (initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.context.exe.ContextInstance.tokenVariableMaps#109], was: [<unreferenced>]
(initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.engine.Collections] Collection found:
[org.jbpm.context.exe.TokenVariableMap.variableInstances#110], was: [<unreferenced>]
(initialized)
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener]
Flushed: 36 insertions, 8 updates, 0 deletions to 36 objects
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener]
Flushed: 23 (re)creations, 0 updates, 0 removals to 23 collections
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.pretty.Printer] listing entities:
  | 2007-01-27 14:16:01,995 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.graph.def.Transition{to=org.jbpm.graph.def.Node#90, events=null,
from=org.jbpm.graph.def.Node#78, exceptionHandlers=null, name=true, id=80,
processDefinition=org.jbpm.graph.def.ProcessDefinition#75}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.context.exe.variableinstance.ByteArrayInstance{processInstance=org.jbpm.graph.exe.ProcessInstance#100,
value=org.jbpm.bytes.ByteArray#105,
tokenVariableMap=org.jbpm.context.exe.TokenVariableMap#110,
converter=org.jbpm.context.exe.converter.SerializableToByteArrayConverter@9cd826,
token=org.jbpm.graph.exe.Token#101, name=jobTask, id=104}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.graph.def.Event{actions=[org.jbpm.graph.def.Action#86],
graphElement=org.jbpm.graph.node.State#82, eventType=node-enter, id=85}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.graph.def.Transition{to=org.jbpm.graph.def.Node#78, events=null,
from=org.jbpm.graph.def.Node#76, exceptionHandlers=null, name=, id=77,
processDefinition=org.jbpm.graph.def.ProcessDefinition#75}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.graph.log.ProcessInstanceCreateLog{index=0, token=org.jbpm.graph.exe.Token#101,
date=2007-01-27 14:16:01, parent=null, id=102}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.graph.exe.Token{comments=null,
processInstance=org.jbpm.graph.exe.ProcessInstance#100, subProcessInstance=null,
start=2007-01-27 14:16:01, isSuspended=false, end=null, node=org.jbpm.graph.def.Node#76,
id=101, version=0, nextLogIndex=3, name=null, isTerminationImplicit=false, children=null,
isAbleToReactivateParent=true, parent=null, nodeEnter=null}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.graph.node.EndState{events=[org.jbpm.graph.def.Event#96],
leavingTransitions=null, action=null, superState=null, exceptionHandlers=null, name=end1,
id=95, arrivingTransitions=[org.jbpm.graph.def.Transition#94], isAsync=false,
processDefinition=org.jbpm.graph.def.ProcessDefinition#75}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.graph.def.Event{actions=[org.jbpm.graph.def.Action#97],
graphElement=org.jbpm.graph.node.EndState#95, eventType=node-enter, id=96}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.graph.node.StartState{events=null,
leavingTransitions=[org.jbpm.graph.def.Transition#77], action=null, superState=null,
exceptionHandlers=null, name=start, id=76, arrivingTransitions=null, isAsync=false,
processDefinition=org.jbpm.graph.def.ProcessDefinition#75}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.scheduler.def.CreateTimerAction{repeat=false, actionExpression=null, dueDate=10
seconds, timerAction=org.jbpm.graph.def.Action#87, referencedAction=null, id=86,
isAsync=false, processDefinition=null, isPropagationAllowed=true, timerName=Wait For File,
event=org.jbpm.graph.def.Event#85, actionDelegation=null, transitionName=Is file complete,
name=null}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.graph.exe.ProcessInstance{start=2007-01-27 14:16:01,
instances=[org.jbpm.module.exe.ModuleInstance#108,
org.jbpm.module.exe.ModuleInstance#109], runtimeActions=null, isSuspended=false, end=null,
superProcessToken=null, rootToken=org.jbpm.graph.exe.Token#101, id=100,
processDefinition=org.jbpm.graph.def.ProcessDefinition#75, version=1}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.graph.def.Event{actions=[org.jbpm.graph.def.Action#84],
graphElement=org.jbpm.graph.node.State#82, eventType=node-leave, id=83}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.context.log.variableinstance.ByteArrayUpdateLog{newValue=org.jbpm.bytes.ByteArray#107,
index=2, variableInstance=org.jbpm.context.exe.VariableInstance#104,
token=org.jbpm.graph.exe.Token#101, oldValue=null, date=2007-01-27 14:16:01, parent=null,
id=106}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.context.exe.ContextInstance{processInstance=org.jbpm.graph.exe.ProcessInstance#100,
id=109, tokenVariableMaps=[org.jbpm.context.exe.TokenVariableMap#110]}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.taskmgmt.def.TaskMgmtDefinition{swimlanes=null, startTask=null, tasks=null,
name=null, id=99, processDefinition=org.jbpm.graph.def.ProcessDefinition#75}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.graph.def.Event{actions=[org.jbpm.graph.def.Action#92],
graphElement=org.jbpm.graph.def.Node#90, eventType=node-enter, id=91}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.graph.def.Transition{to=org.jbpm.graph.def.Node#78, events=null,
from=org.jbpm.graph.def.Node#82, exceptionHandlers=null, name=File isn't complete,
id=89, processDefinition=org.jbpm.graph.def.ProcessDefinition#75}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.graph.node.Decision{events=null, action=null,
leavingTransitions=[org.jbpm.graph.def.Transition#80, org.jbpm.graph.def.Transition#81],
superState=null, id=78, decisionConditions=null,
processDefinition=org.jbpm.graph.def.ProcessDefinition#75, isAsync=false,
decisionDelegation=org.jbpm.instantiation.Delegation#79, decisionExpression=null,
exceptionHandlers=null, name=Is file complete,
arrivingTransitions=[org.jbpm.graph.def.Transition#77, org.jbpm.graph.def.Transition#89]}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.context.exe.TokenVariableMap{variableInstances=[org.jbpm.context.exe.VariableInstance#104],
token=org.jbpm.graph.exe.Token#101,
contextInstance=org.jbpm.context.exe.ContextInstance#109, id=110}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.bytes.ByteArray{name=null,
byteBlocks=[2c6d8085f3f280a0e3efedaef3f0f3f3aee2e1f4e3e8aef1f5e5f5e5aed4e5f3f4caefe2d4e1f3ebbe4c21d0d08bec71828080f8f280a4e3efedaef3f0f3f3aee2e1f4e3e8aef1f5e5f5e5aec1e2f3f4f2e1e3f4caefe2d4e1f3eba6b55cf50a61b6bd828085ca8088e6e9ece5d3e9fae5ca8089ece1f3f4c3e8e5e3ebca808cece1f3f4cdefe4e9e6e9e5e4cc8088f4e1f3ebc6e9ece5f4808ecceae1f6e1afe9efafc6e9ece5bbcc8089f7e1f4e3e8c6e9ece5f180fe8082f8f080808080808080b480808190e47ccf5480808190e0a50136f3f2808ceae1f6e1aee9efaec6e9ece584ad24c58e8d647f838081cc8084f0e1f4e8f48092cceae1f6e1afece1eee7afd3f4f2e9eee7bbf8f0f48090c3badcc6e9ece5d4e5f3f4dcf4e5f3f4f78280dcf8f3f180fe8084f48094c3badcc6e9ece5d4e5f3f4dcf4e5f3f4e6e9ece5f78280dcf8],
id=105}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer]
org.jbpm.instantiation.Delegation{configType=field, configuration=null,
className=com.spss.batch.queue.actions.StartJobProcess, id=93,
processDefinition=org.jbpm.graph.def.ProcessDefinition#75}
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.pretty.Printer] more......
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC
connection
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.SQL] insert into
abom_own.JBPM_PROCESSDEFINITION (NAME_, VERSION_, ISTERMINATIONIMPLICIT_, STARTSTATE_,
ID_) values (?, ?, ?, ?, ?)
  | 2007-01-27 14:16:02,011 INFO  [STDOUT] Hibernate: insert into
abom_own.JBPM_PROCESSDEFINITION (NAME_, VERSION_, ISTERMINATIONIMPLICIT_, STARTSTATE_,
ID_) values (?, ?, ?, ?, ?)
  | 2007-01-27 14:16:02,011 DEBUG [org.hibernate.jdbc.AbstractBatcher] Executing batch
size: 1
  | 2007-01-27 14:16:02,105 DEBUG [org.hibernate.jdbc.Expectations] success of batch
update unknown: 0
  | 2007-01-27 14:16:02,105 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:02,105 DEBUG [org.hibernate.jdbc.ConnectionManager] skipping
aggressive-release due to flush cycle
  | 2007-01-27 14:16:02,105 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:02,105 DEBUG [org.hibernate.SQL] insert into abom_own.JBPM_NODE
(NAME_, PROCESSDEFINITION_, ISASYNC_, ACTION_, SUPERSTATE_, CLASS_, ID_) values (?, ?, ?,
?, ?, 'R', ?)
  | 2007-01-27 14:16:02,105 INFO  [STDOUT] Hibernate: insert into abom_own.JBPM_NODE
(NAME_, PROCESSDEFINITION_, ISASYNC_, ACTION_, SUPERSTATE_, CLASS_, ID_) values (?, ?, ?,
?, ?, 'R', ?)
  | 2007-01-27 14:16:02,105 DEBUG [org.hibernate.jdbc.AbstractBatcher] Executing batch
size: 1
  | 2007-01-27 14:16:02,136 DEBUG [org.hibernate.jdbc.Expectations] success of batch
update unknown: 0
  | 2007-01-27 14:16:02,136 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:02,136 DEBUG [org.hibernate.jdbc.ConnectionManager] skipping
aggressive-release due to flush cycle
  | 2007-01-27 14:16:02,136 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:02,136 DEBUG [org.hibernate.SQL] insert into abom_own.JBPM_TRANSITION
(NAME_, PROCESSDEFINITION_, FROM_, TO_, ID_) values (?, ?, ?, ?, ?)
  | 2007-01-27 14:16:02,136 INFO  [STDOUT] Hibernate: insert into abom_own.JBPM_TRANSITION
(NAME_, PROCESSDEFINITION_, FROM_, TO_, ID_) values (?, ?, ?, ?, ?)
  | 2007-01-27 14:16:02,136 DEBUG [org.hibernate.jdbc.AbstractBatcher] Executing batch
size: 1
  | 2007-01-27 14:16:02,152 DEBUG [org.hibernate.jdbc.Expectations] success of batch
update unknown: 0
  | 2007-01-27 14:16:02,152 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:02,152 DEBUG [org.hibernate.jdbc.ConnectionManager] skipping
aggressive-release due to flush cycle
  | 2007-01-27 14:16:02,152 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:02,152 DEBUG [org.hibernate.SQL] insert into abom_own.JBPM_DELEGATION
(CLASSNAME_, CONFIGURATION_, CONFIGTYPE_, PROCESSDEFINITION_, ID_) values (?, ?, ?, ?, ?)
  | 2007-01-27 14:16:02,152 INFO  [STDOUT] Hibernate: insert into abom_own.JBPM_DELEGATION
(CLASSNAME_, CONFIGURATION_, CONFIGTYPE_, PROCESSDEFINITION_, ID_) values (?, ?, ?, ?, ?)
  | 2007-01-27 14:16:02,152 DEBUG [org.hibernate.jdbc.AbstractBatcher] Executing batch
size: 1
  | 2007-01-27 14:16:02,183 DEBUG [org.hibernate.jdbc.Expectations] success of batch
update unknown: 0
  | 2007-01-27 14:16:02,183 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:02,183 DEBUG [org.hibernate.jdbc.ConnectionManager] skipping
aggressive-release due to flush cycle
  | 2007-01-27 14:16:02,183 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:02,183 DEBUG [org.hibernate.SQL] insert into abom_own.JBPM_NODE
(NAME_, PROCESSDEFINITION_, ISASYNC_, ACTION_, SUPERSTATE_, DECISIONEXPRESSION_,
DECISIONDELEGATION, CLASS_, ID_) values (?, ?, ?, ?, ?, ?, ?, 'D', ?)
  | 2007-01-27 14:16:02,183 INFO  [STDOUT] Hibernate: insert into abom_own.JBPM_NODE
(NAME_, PROCESSDEFINITION_, ISASYNC_, ACTION_, SUPERSTATE_, DECISIONEXPRESSION_,
DECISIONDELEGATION, CLASS_, ID_) values (?, ?, ?, ?, ?, ?, ?, 'D', ?)
  | 2007-01-27 14:16:02,183 DEBUG [org.hibernate.jdbc.AbstractBatcher] Executing batch
size: 1
  | 2007-01-27 14:16:02,214 DEBUG [org.hibernate.jdbc.Expectations] success of batch
update unknown: 0
  | 2007-01-27 14:16:02,214 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:02,214 DEBUG [org.hibernate.jdbc.ConnectionManager] skipping
aggressive-release due to flush cycle
  | 2007-01-27 14:16:02,214 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2007-01-27 14:16:02,214 DEBUG [org.hibernate.SQL] insert into abom_own.JBPM_TRANSITION
(NAME_, PROCESSDEFINITION_, FROM_, TO_, ID_) values (?, ?, ?, ?, ?)
  | 2007-01-27 14:16:02,214 INFO  [STDOUT] Hibernate: insert into abom_own.JBPM_TRANSITION
(NAME_, PROCESSDEFINITION_, FROM_, TO_, ID_) values (?, ?, ?, ?, ?)
  | 2007-01-27 14:16:02,214 DEBUG [org.hibernate.jdbc.AbstractBatcher] reusing prepared
statement
  | 2007-01-27 14:16:02,214 DEBUG [org.hibernate.SQL] insert into abom_own.JBPM_TRANSITION
(NAME_, PROCESSDEFINITION_, FROM_, TO_, ID_) values (?, ?, ?, ?, ?)
  | 2007-01-27 14:16:02,214 INFO  [STDOUT] Hibernate: insert into abom_own.JBPM_TRANSITION
(NAME_, PROCESSDEFINITION_, FROM_, TO_, ID_) values (?, ?, ?, ?, ?)
  | 2007-01-27 14:16:02,214 DEBUG [org.hibernate.jdbc.AbstractBatcher] Executing batch
size: 2
  | 2007-01-27 14:16:02,230 DEBUG [org.hibernate.jdbc.Expectations] success of batch
update unknown: 0
  | 2007-01-27 14:16:02,230 DEBUG [org.hibernate.jdbc.Expectations] success of batch
update unknown: 1
  | 2007-01-27 14:16:02,230 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close
PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2007-01-27 14:16:02,230 DEBUG [org.hibernate.jdbc.ConnectionManager] skipping
aggressive-release due to flush cycle
  | 2007-01-27 14:16:02,230 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open
PreparedState

View the original post :
http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4007941#...

Reply to the post :
http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&a...

2026

2025

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

2007

2006

[JBoss jBPM] - Re: Timer Problem