4 Replies Latest reply on Dec 30, 2005 5:40 AM by kukeltje

    executionService.close() gives error all the time

      I get "attempt to insert duplicate key row" kind of database errors all the time while executionService.close() is running. Somehow jbpm tries to insert a row double times or sometimes with wrong parameters.

      An example trace is as below:

      12:58:28,624 INFO [STDOUT] 12:58:28,624 trace [EntityPersister] Inserting entity: [org.jbpm.model.execution.impl.TokenImpl#6553993]
      12:58:28,624 INFO [STDOUT] 12:58:28,624 trace [BatcherImpl] about to open: 0 open PreparedStatements, 0 open ResultSets
      12:58:28,624 INFO [STDOUT] 12:58:28,624 debug [SQL] insert into JBPM_TOKEN (name, processInstance, parent, state, actorId, start_, end_, reactivationJoin, subProcessInstance, lastStateLog, id) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
      12:58:28,634 INFO [STDOUT] 12:58:28,624 trace [BatcherImpl] preparing statement
      12:58:28,634 INFO [STDOUT] 12:58:28,634 trace [EntityPersister] Dehydrating entity: [org.jbpm.model.execution.impl.TokenImpl#6553993]
      12:58:28,634 INFO [STDOUT] 12:58:28,634 trace [StringType] binding 'root' to parameter: 1
      12:58:28,634 INFO [STDOUT] 12:58:28,634 trace [LongType] binding null to parameter: 2
      12:58:28,634 INFO [STDOUT] 12:58:28,634 trace [LongType] binding null to parameter: 3
      12:58:28,634 INFO [STDOUT] 12:58:28,634 trace [LongType] binding '13107241' to parameter: 4
      12:58:28,634 INFO [STDOUT] 12:58:28,634 trace [StringType] binding null to parameter: 5
      12:58:28,634 INFO [STDOUT] 12:58:28,634 trace [TimestampType] binding '2005-12-29 12:58:27' to parameter: 6
      12:58:28,634 INFO [STDOUT] 12:58:28,634 trace [TimestampType] binding null to parameter: 7
      12:58:28,634 INFO [STDOUT] 12:58:28,634 trace [LongType] binding null to parameter: 8
      12:58:28,634 INFO [STDOUT] 12:58:28,634 trace [LongType] binding null to parameter: 9
      12:58:28,634 INFO [STDOUT] 12:58:28,634 trace [LongType] binding null to parameter: 10
      12:58:28,634 INFO [STDOUT] 12:58:28,634 trace [LongType] binding '6553993' to parameter: 11



      Does anybody know a possible solution to this?




        • 1. Re: executionService.close() gives error all the time
          kukeltje

          I do not see any error here, just INFO. Did you post the wrong log?

          • 2. Re: executionService.close() gives error all the time

            It's a bit too long that's why I did not post all, but I guess I should. Because we really cannot go forward unless we solve this problem. I would appreciate any help greatly!


            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] jBpm configuration:
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [hibernate.connection.datasource] java:/TPAODS
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [hibernate.connection.driver_class] com.sybase.jdbc2.jdbc.SybDriver
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [hibernate.connection.release_mode] after_statement
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [hibernate.dialect] net.sf.hibernate.dialect.Sybase11_9_2Dialect
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [hibernate.session_factory_name] hibernate/HibernateFactory
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [hibernate.show_sql] true
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [hibernate.transaction.factory_class] net.sf.hibernate.transaction.JTATransactionFactory
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [hibernate.transaction.manager_lookup_class] net.sf.hibernate.transaction.JBossTransactionManagerLookup
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.apply.transactions] no
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.create.tables] never
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.create.tables.log] false
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.create.tables.query] SELECT ID FROM JBPM_DEFINITION WHERE ID = 1
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.execute.actions] true
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.file.mgr] database
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.id.generator] default
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.id.generator.block.size] 100
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.id.generator.configuration] same
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.id.generator.node.id] 3
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.log.default] debug
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.log.package.org.jbpm] debug
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.log.stdout] on
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.persistence.session.factory] hibernate
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.scheduler.wait.period] 5000
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.service.factory] default
            2005-12-29 17:01:35,523 INFO [STDOUT] 17:01:35,523 debug [JbpmConfiguration] [jbpm.validate.xml] true
            2005-12-29 17:01:35,543 INFO [STDOUT] 17:01:35,543 debug [JbpmConfiguration] for key 'jbpm.file.mgr', an object of type 'org.jbpm.persistence.filemgr.DatabaseFileMgr' was instantiated
            2005-12-29 17:01:35,583 INFO [STDOUT] 17:01:35,583 debug [HibernateSessionFactory] creating the hibernate session factory
            2005-12-29 17:01:35,623 INFO [STDOUT] 17:01:35,623 info [Environment] Hibernate 2.1.6
            2005-12-29 17:01:35,643 INFO [STDOUT] 17:01:35,643 info [Environment] hibernate.properties not found
            2005-12-29 17:01:35,653 INFO [STDOUT] 17:01:35,653 info [Environment] using CGLIB reflection optimizer
            2005-12-29 17:01:35,683 INFO [STDOUT] 17:01:35,683 info [Configuration] Mapping resource: org/jbpm/persistence/hibernate/SequenceBlock.hbm.xml
            2005-12-29 17:01:35,713 INFO [STDOUT] 17:01:35,713 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:35,723 INFO [STDOUT] 17:01:35,723 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:35,954 INFO [STDOUT] 17:01:35,954 info [Binder] Mapping class: org.jbpm.persistence.hibernate.SequenceBlock -> JBPM_SEQUENCEBLOCK
            2005-12-29 17:01:36,354 INFO [STDOUT] 17:01:36,354 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:36,404 INFO [STDOUT] 17:01:36,404 debug [Binder] Mapped property: nextId -> nextId, type: long
            2005-12-29 17:01:36,434 INFO [STDOUT] 17:01:36,434 info [Configuration] Mapping resource: org/jbpm/model/definition/impl/ActionImpl.hbm.xml
            2005-12-29 17:01:36,444 INFO [STDOUT] 17:01:36,444 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:36,444 INFO [STDOUT] 17:01:36,444 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:36,454 INFO [STDOUT] 17:01:36,454 info [Binder] Mapping class: org.jbpm.model.definition.impl.ActionImpl -> JBPM_ACTION
            2005-12-29 17:01:36,454 INFO [STDOUT] 17:01:36,454 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:36,454 INFO [STDOUT] 17:01:36,454 debug [Binder] Mapped property: element -> element_type, element, type: object
            2005-12-29 17:01:36,454 INFO [STDOUT] 17:01:36,454 debug [Binder] Mapped property: index -> index_, type: integer
            2005-12-29 17:01:36,474 INFO [STDOUT] 17:01:36,474 debug [Binder] Mapped property: eventType -> eventType, type: org.jbpm.model.definition.EventType
            2005-12-29 17:01:36,534 INFO [STDOUT] 17:01:36,534 debug [Binder] Mapped property: actionDelegation -> actionDelegation, type: org.jbpm.model.definition.impl.DelegationImpl
            2005-12-29 17:01:36,574 INFO [STDOUT] 17:01:36,574 info [Configuration] Mapping resource: org/jbpm/model/definition/impl/DefinitionImpl.hbm.xml
            2005-12-29 17:01:36,585 INFO [STDOUT] 17:01:36,585 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:36,585 INFO [STDOUT] 17:01:36,585 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:36,595 INFO [STDOUT] 17:01:36,595 info [Binder] Mapping class: org.jbpm.model.definition.impl.DefinitionImpl -> JBPM_DEFINITION
            2005-12-29 17:01:36,605 INFO [STDOUT] 17:01:36,605 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:36,605 INFO [STDOUT] 17:01:36,605 debug [Binder] Mapped property: name -> name, type: string
            2005-12-29 17:01:36,605 INFO [STDOUT] 17:01:36,605 debug [Binder] Mapped property: description -> description, type: string
            2005-12-29 17:01:36,655 INFO [STDOUT] 17:01:36,655 debug [Binder] Mapped property: definition -> definition, type: org.jbpm.model.definition.impl.DefinitionImpl
            2005-12-29 17:01:36,705 INFO [STDOUT] 17:01:36,705 debug [Binder] Mapped property: actions, type: java.util.List
            2005-12-29 17:01:36,705 INFO [STDOUT] 17:01:36,705 debug [Binder] Mapped property: version -> version_, type: integer
            2005-12-29 17:01:36,745 INFO [STDOUT] 17:01:36,745 debug [Binder] Mapped property: startState -> startState, type: org.jbpm.model.definition.impl.StartStateImpl
            2005-12-29 17:01:36,745 INFO [STDOUT] 17:01:36,745 debug [Binder] Mapped property: endState -> endState, type: org.jbpm.model.definition.impl.EndStateImpl
            2005-12-29 17:01:36,755 INFO [STDOUT] 17:01:36,755 debug [Binder] Mapped property: swimlanes, type: java.util.Map
            2005-12-29 17:01:36,765 INFO [STDOUT] 17:01:36,765 debug [Binder] Mapped property: types, type: java.util.List
            2005-12-29 17:01:36,765 INFO [STDOUT] 17:01:36,765 debug [Binder] Mapped property: nodes, type: java.util.Map
            2005-12-29 17:01:36,765 INFO [STDOUT] 17:01:36,765 debug [Binder] Mapped property: variables, type: java.util.Map
            2005-12-29 17:01:36,765 INFO [STDOUT] 17:01:36,765 info [Configuration] Mapping resource: org/jbpm/model/definition/impl/NodeImpl.hbm.xml
            2005-12-29 17:01:36,775 INFO [STDOUT] 17:01:36,775 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:36,775 INFO [STDOUT] 17:01:36,775 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:36,795 INFO [STDOUT] 17:01:36,795 info [Binder] Mapping class: org.jbpm.model.definition.impl.NodeImpl -> JBPM_NODE
            2005-12-29 17:01:36,795 INFO [STDOUT] 17:01:36,795 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:36,795 INFO [STDOUT] 17:01:36,795 debug [Binder] Mapped property: name -> name, type: string
            2005-12-29 17:01:36,795 INFO [STDOUT] 17:01:36,795 debug [Binder] Mapped property: description -> description, type: string
            2005-12-29 17:01:36,795 INFO [STDOUT] 17:01:36,795 debug [Binder] Mapped property: definition -> definition, type: org.jbpm.model.definition.impl.DefinitionImpl
            2005-12-29 17:01:36,795 INFO [STDOUT] 17:01:36,795 debug [Binder] Mapped property: actions, type: java.util.List
            2005-12-29 17:01:36,805 INFO [STDOUT] 17:01:36,805 debug [Binder] Mapped property: leavingTransitions, type: java.util.Set
            2005-12-29 17:01:36,915 INFO [STDOUT] 17:01:36,915 info [Binder] Mapping subclass: org.jbpm.model.definition.impl.StateImpl -> JBPM_NODE
            2005-12-29 17:01:36,915 INFO [STDOUT] 17:01:36,915 debug [Binder] Mapped property: swimlane -> swimlane, type: org.jbpm.model.definition.impl.SwimlaneImpl
            2005-12-29 17:01:36,915 INFO [STDOUT] 17:01:36,915 debug [Binder] Mapped property: assignmentRequired -> assignmentRequired, type: boolean
            2005-12-29 17:01:36,925 INFO [STDOUT] 17:01:36,925 debug [Binder] Mapped property: stateAuthentication -> stateAuthentication, type: org.jbpm.model.definition.StateAuthentication
            2005-12-29 17:01:36,925 INFO [STDOUT] 17:01:36,925 info [Binder] Mapping subclass: org.jbpm.model.definition.impl.StartStateImpl -> JBPM_NODE
            2005-12-29 17:01:36,925 INFO [STDOUT] 17:01:36,925 info [Binder] Mapping subclass: org.jbpm.model.definition.impl.EndStateImpl -> JBPM_NODE
            2005-12-29 17:01:36,935 INFO [STDOUT] 17:01:36,935 info [Binder] Mapping subclass: org.jbpm.model.definition.impl.ProcessStateImpl -> JBPM_NODE
            2005-12-29 17:01:36,935 INFO [STDOUT] 17:01:36,935 debug [Binder] Mapped property: processInvocationDelegation -> delegation, type: org.jbpm.model.definition.impl.DelegationImpl
            2005-12-29 17:01:36,945 INFO [STDOUT] 17:01:36,945 info [Binder] Mapping subclass: org.jbpm.model.definition.impl.MilestoneImpl -> JBPM_NODE
            2005-12-29 17:01:36,965 INFO [STDOUT] 17:01:36,955 info [Binder] Mapping subclass: org.jbpm.model.definition.impl.DecisionImpl -> JBPM_NODE
            2005-12-29 17:01:36,965 INFO [STDOUT] 17:01:36,965 debug [Binder] Mapped property: decisionDelegation -> delegation, type: org.jbpm.model.definition.impl.DelegationImpl
            2005-12-29 17:01:36,975 INFO [STDOUT] 17:01:36,975 info [Binder] Mapping subclass: org.jbpm.model.definition.impl.ForkImpl -> JBPM_NODE
            2005-12-29 17:01:36,975 INFO [STDOUT] 17:01:36,975 debug [Binder] Mapped property: forkDelegation -> delegation, type: org.jbpm.model.definition.impl.DelegationImpl
            2005-12-29 17:01:36,985 INFO [STDOUT] 17:01:36,985 debug [Binder] Mapped property: correspondingJoin -> correspondingJoin, type: org.jbpm.model.definition.impl.JoinImpl
            2005-12-29 17:01:36,985 INFO [STDOUT] 17:01:36,985 info [Binder] Mapping subclass: org.jbpm.model.definition.impl.JoinImpl -> JBPM_NODE
            2005-12-29 17:01:36,985 INFO [STDOUT] 17:01:36,985 debug [Binder] Mapped property: joinDelegation -> delegation, type: org.jbpm.model.definition.impl.DelegationImpl
            2005-12-29 17:01:36,985 INFO [STDOUT] 17:01:36,985 debug [Binder] Mapped property: correspondingFork -> correspondingFork, type: org.jbpm.model.definition.impl.ForkImpl
            2005-12-29 17:01:36,985 INFO [STDOUT] 17:01:36,985 info [Configuration] Mapping resource: org/jbpm/model/definition/impl/TransitionImpl.hbm.xml
            2005-12-29 17:01:36,995 INFO [STDOUT] 17:01:36,995 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:36,995 INFO [STDOUT] 17:01:36,995 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:37,015 INFO [STDOUT] 17:01:37,015 info [Binder] Mapping class: org.jbpm.model.definition.impl.TransitionImpl -> JBPM_TRANSITION
            2005-12-29 17:01:37,015 INFO [STDOUT] 17:01:37,015 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:37,015 INFO [STDOUT] 17:01:37,015 debug [Binder] Mapped property: name -> name, type: string
            2005-12-29 17:01:37,015 INFO [STDOUT] 17:01:37,015 debug [Binder] Mapped property: description -> description, type: string
            2005-12-29 17:01:37,015 INFO [STDOUT] 17:01:37,015 debug [Binder] Mapped property: definition -> definition, type: org.jbpm.model.definition.impl.DefinitionImpl
            2005-12-29 17:01:37,015 INFO [STDOUT] 17:01:37,015 debug [Binder] Mapped property: actions, type: java.util.List
            2005-12-29 17:01:37,015 INFO [STDOUT] 17:01:37,015 debug [Binder] Mapped property: from -> from_, type: org.jbpm.model.definition.impl.NodeImpl
            2005-12-29 17:01:37,015 INFO [STDOUT] 17:01:37,015 debug [Binder] Mapped property: to -> to_, type: org.jbpm.model.definition.impl.NodeImpl
            2005-12-29 17:01:37,025 INFO [STDOUT] 17:01:37,015 info [Configuration] Mapping resource: org/jbpm/model/definition/impl/SwimlaneImpl.hbm.xml
            2005-12-29 17:01:37,025 INFO [STDOUT] 17:01:37,025 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:37,025 INFO [STDOUT] 17:01:37,025 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:37,035 INFO [STDOUT] 17:01:37,035 info [Binder] Mapping class: org.jbpm.model.definition.impl.SwimlaneImpl -> JBPM_SWIMLANE
            2005-12-29 17:01:37,035 INFO [STDOUT] 17:01:37,035 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:37,035 INFO [STDOUT] 17:01:37,035 debug [Binder] Mapped property: name -> name, type: string
            2005-12-29 17:01:37,035 INFO [STDOUT] 17:01:37,035 debug [Binder] Mapped property: description -> description, type: string
            2005-12-29 17:01:37,035 INFO [STDOUT] 17:01:37,035 debug [Binder] Mapped property: definition -> definition, type: org.jbpm.model.definition.impl.DefinitionImpl
            2005-12-29 17:01:37,035 INFO [STDOUT] 17:01:37,035 debug [Binder] Mapped property: assignmentDelegation -> delegation, type: org.jbpm.model.definition.impl.DelegationImpl
            2005-12-29 17:01:37,035 INFO [STDOUT] 17:01:37,035 info [Configuration] Mapping resource: org/jbpm/model/definition/impl/TypeImpl.hbm.xml
            2005-12-29 17:01:37,045 INFO [STDOUT] 17:01:37,045 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:37,045 INFO [STDOUT] 17:01:37,045 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:37,055 INFO [STDOUT] 17:01:37,055 info [Binder] Mapping class: org.jbpm.model.definition.impl.TypeImpl -> JBPM_TYPE
            2005-12-29 17:01:37,055 INFO [STDOUT] 17:01:37,055 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:37,055 INFO [STDOUT] 17:01:37,055 debug [Binder] Mapped property: index -> index_, type: integer
            2005-12-29 17:01:37,055 INFO [STDOUT] 17:01:37,055 debug [Binder] Mapped property: description -> description, type: string
            2005-12-29 17:01:37,055 INFO [STDOUT] 17:01:37,055 debug [Binder] Mapped property: javaType -> javaType, type: string
            2005-12-29 17:01:37,055 INFO [STDOUT] 17:01:37,055 debug [Binder] Mapped property: definition -> definition, type: org.jbpm.model.definition.impl.DefinitionImpl
            2005-12-29 17:01:37,055 INFO [STDOUT] 17:01:37,055 debug [Binder] Mapped property: serializerDelegation -> delegation, type: org.jbpm.model.definition.impl.DelegationImpl
            2005-12-29 17:01:37,055 INFO [STDOUT] 17:01:37,055 debug [Binder] Mapped property: variables, type: java.util.Map
            2005-12-29 17:01:37,055 INFO [STDOUT] 17:01:37,055 info [Configuration] Mapping resource: org/jbpm/model/definition/impl/VariableImpl.hbm.xml
            2005-12-29 17:01:37,065 INFO [STDOUT] 17:01:37,065 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:37,065 INFO [STDOUT] 17:01:37,065 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:37,075 INFO [STDOUT] 17:01:37,075 info [Binder] Mapping class: org.jbpm.model.definition.impl.VariableImpl -> JBPM_VARIABLE
            2005-12-29 17:01:37,075 INFO [STDOUT] 17:01:37,075 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:37,075 INFO [STDOUT] 17:01:37,075 debug [Binder] Mapped property: name -> name, type: string
            2005-12-29 17:01:37,075 INFO [STDOUT] 17:01:37,075 debug [Binder] Mapped property: definition -> definition, type: org.jbpm.model.definition.impl.DefinitionImpl
            2005-12-29 17:01:37,075 INFO [STDOUT] 17:01:37,075 debug [Binder] Mapped property: type -> type_, type: org.jbpm.model.definition.impl.TypeImpl
            2005-12-29 17:01:37,075 INFO [STDOUT] 17:01:37,075 info [Configuration] Mapping resource: org/jbpm/model/definition/impl/DelegationImpl.hbm.xml
            2005-12-29 17:01:37,085 INFO [STDOUT] 17:01:37,085 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:37,085 INFO [STDOUT] 17:01:37,085 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:37,095 INFO [STDOUT] 17:01:37,095 info [Binder] Mapping class: org.jbpm.model.definition.impl.DelegationImpl -> JBPM_DELEGATION
            2005-12-29 17:01:37,095 INFO [STDOUT] 17:01:37,095 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:37,095 INFO [STDOUT] 17:01:37,095 debug [Binder] Mapped property: definition -> definition, type: org.jbpm.model.definition.impl.DefinitionImpl
            2005-12-29 17:01:37,095 INFO [STDOUT] 17:01:37,095 debug [Binder] Mapped property: className -> className, type: string
            2005-12-29 17:01:37,095 INFO [STDOUT] 17:01:37,095 debug [Binder] Mapped property: configuration -> configuration, type: string
            2005-12-29 17:01:37,105 INFO [STDOUT] 17:01:37,105 info [Configuration] Mapping resource: org/jbpm/model/definition/impl/FileImpl.hbm.xml
            2005-12-29 17:01:37,115 INFO [STDOUT] 17:01:37,105 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:37,115 INFO [STDOUT] 17:01:37,115 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:37,125 INFO [STDOUT] 17:01:37,125 info [Binder] Mapping class: org.jbpm.model.definition.impl.FileImpl -> JBPM_FILE
            2005-12-29 17:01:37,125 INFO [STDOUT] 17:01:37,125 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:37,125 INFO [STDOUT] 17:01:37,125 debug [Binder] Mapped property: name -> name, type: string
            2005-12-29 17:01:37,125 INFO [STDOUT] 17:01:37,125 debug [Binder] Mapped property: definitionId -> definitionId, type: long
            2005-12-29 17:01:37,125 INFO [STDOUT] 17:01:37,125 debug [Binder] Mapped property: bytes -> bytes, type: binary
            2005-12-29 17:01:37,135 INFO [STDOUT] 17:01:37,135 info [Configuration] Mapping resource: org/jbpm/model/execution/impl/ProcessInstanceImpl.hbm.xml
            2005-12-29 17:01:37,145 INFO [STDOUT] 17:01:37,145 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:37,145 INFO [STDOUT] 17:01:37,145 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:37,155 INFO [STDOUT] 17:01:37,155 info [Binder] Mapping class: org.jbpm.model.execution.impl.ProcessInstanceImpl -> JBPM_PROCESSINSTANCE
            2005-12-29 17:01:37,155 INFO [STDOUT] 17:01:37,155 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:37,165 INFO [STDOUT] 17:01:37,165 debug [Binder] Mapped property: definition -> definition, type: org.jbpm.model.definition.impl.DefinitionImpl
            2005-12-29 17:01:37,205 INFO [STDOUT] 17:01:37,205 debug [Binder] Mapped property: root -> root, type: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,205 INFO [STDOUT] 17:01:37,205 debug [Binder] Mapped property: superProcessToken -> superProcessToken, type: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,205 INFO [STDOUT] 17:01:37,205 debug [Binder] Mapped property: start -> start_, type: timestamp
            2005-12-29 17:01:37,205 INFO [STDOUT] 17:01:37,205 debug [Binder] Mapped property: end -> end_, type: timestamp
            2005-12-29 17:01:37,205 INFO [STDOUT] 17:01:37,205 info [Configuration] Mapping resource: org/jbpm/model/execution/impl/TokenImpl.hbm.xml
            2005-12-29 17:01:37,215 INFO [STDOUT] 17:01:37,215 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:37,215 INFO [STDOUT] 17:01:37,215 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:37,235 INFO [STDOUT] 17:01:37,235 info [Binder] Mapping class: org.jbpm.model.execution.impl.TokenImpl -> JBPM_TOKEN
            2005-12-29 17:01:37,235 INFO [STDOUT] 17:01:37,235 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:37,235 INFO [STDOUT] 17:01:37,235 debug [Binder] Mapped property: name -> name, type: string
            2005-12-29 17:01:37,235 INFO [STDOUT] 17:01:37,235 debug [Binder] Mapped property: processInstance -> processInstance, type: org.jbpm.model.execution.impl.ProcessInstanceImpl
            2005-12-29 17:01:37,235 INFO [STDOUT] 17:01:37,235 debug [Binder] Mapped property: parent -> parent, type: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,235 INFO [STDOUT] 17:01:37,235 debug [Binder] Mapped property: children, type: java.util.Map
            2005-12-29 17:01:37,235 INFO [STDOUT] 17:01:37,235 debug [Binder] Mapped property: state -> state, type: org.jbpm.model.definition.impl.StateImpl
            2005-12-29 17:01:37,235 INFO [STDOUT] 17:01:37,235 debug [Binder] Mapped property: actorId -> actorId, type: string
            2005-12-29 17:01:37,245 INFO [STDOUT] 17:01:37,245 debug [Binder] Mapped property: variableInstances, type: java.util.Map
            2005-12-29 17:01:37,245 INFO [STDOUT] 17:01:37,245 debug [Binder] Mapped property: start -> start_, type: timestamp
            2005-12-29 17:01:37,245 INFO [STDOUT] 17:01:37,245 debug [Binder] Mapped property: end -> end_, type: timestamp
            2005-12-29 17:01:37,245 INFO [STDOUT] 17:01:37,245 debug [Binder] Mapped property: reactivationJoin -> reactivationJoin, type: org.jbpm.model.definition.impl.JoinImpl
            2005-12-29 17:01:37,245 INFO [STDOUT] 17:01:37,245 debug [Binder] Mapped property: subProcessInstance -> subProcessInstance, type: org.jbpm.model.execution.impl.ProcessInstanceImpl
            2005-12-29 17:01:37,245 INFO [STDOUT] 17:01:37,245 debug [Binder] Mapped property: lastStateLog -> lastStateLog, type: org.jbpm.model.log.impl.StateLogImpl
            2005-12-29 17:01:37,265 INFO [STDOUT] 17:01:37,265 debug [Binder] Mapped property: invocationLogs, type: java.util.SortedSet
            2005-12-29 17:01:37,265 INFO [STDOUT] 17:01:37,265 info [Configuration] Mapping resource: org/jbpm/model/execution/impl/VariableInstanceImpl.hbm.xml
            2005-12-29 17:01:37,265 INFO [STDOUT] 17:01:37,265 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:37,265 INFO [STDOUT] 17:01:37,265 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:37,296 INFO [STDOUT] 17:01:37,296 info [Binder] Mapping class: org.jbpm.model.execution.impl.VariableInstanceImpl -> JBPM_VARIABLEINSTANCE
            2005-12-29 17:01:37,296 INFO [STDOUT] 17:01:37,296 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:37,296 INFO [STDOUT] 17:01:37,296 debug [Binder] Mapped property: name -> name, type: string
            2005-12-29 17:01:37,296 INFO [STDOUT] 17:01:37,296 debug [Binder] Mapped property: serializedValue -> serializedValue, type: string
            2005-12-29 17:01:37,346 INFO [STDOUT] 17:01:37,346 debug [Binder] Mapped property: defaultType -> defaultType, type: org.jbpm.model.definition.DefaultType
            2005-12-29 17:01:37,346 INFO [STDOUT] 17:01:37,346 debug [Binder] Mapped property: type -> type_, type: org.jbpm.model.definition.impl.TypeImpl
            2005-12-29 17:01:37,346 INFO [STDOUT] 17:01:37,346 debug [Binder] Mapped property: token -> token, type: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,346 INFO [STDOUT] 17:01:37,346 debug [Binder] Mapped property: updateLogs, type: java.util.SortedSet
            2005-12-29 17:01:37,346 INFO [STDOUT] 17:01:37,346 info [Configuration] Mapping resource: org/jbpm/model/log/impl/InvocationLogImpl.hbm.xml
            2005-12-29 17:01:37,356 INFO [STDOUT] 17:01:37,356 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:37,356 INFO [STDOUT] 17:01:37,356 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:37,366 INFO [STDOUT] 17:01:37,366 info [Binder] Mapping class: org.jbpm.model.log.impl.InvocationLogImpl -> JBPM_INVOKELOG
            2005-12-29 17:01:37,366 INFO [STDOUT] 17:01:37,366 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:37,366 INFO [STDOUT] 17:01:37,366 debug [Binder] Mapped property: actorId -> actorId, type: string
            2005-12-29 17:01:37,366 INFO [STDOUT] 17:01:37,366 debug [Binder] Mapped property: token -> token, type: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,366 INFO [STDOUT] 17:01:37,366 debug [Binder] Mapped property: date -> date_, type: timestamp
            2005-12-29 17:01:37,386 INFO [STDOUT] 17:01:37,386 debug [Binder] Mapped property: serviceMethod -> serviceMethod, type: org.jbpm.impl.ServiceMethod
            2005-12-29 17:01:37,386 INFO [STDOUT] 17:01:37,386 debug [Binder] Mapped property: isUndoable -> isUndoable, type: boolean
            2005-12-29 17:01:37,386 INFO [STDOUT] 17:01:37,386 debug [Binder] Mapped property: executionLogs, type: java.util.List
            2005-12-29 17:01:37,386 INFO [STDOUT] 17:01:37,386 info [Configuration] Mapping resource: org/jbpm/model/log/impl/ExecutionLogImpl.hbm.xml
            2005-12-29 17:01:37,396 INFO [STDOUT] 17:01:37,396 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:37,396 INFO [STDOUT] 17:01:37,396 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:37,416 INFO [STDOUT] 17:01:37,416 info [Binder] Mapping class: org.jbpm.model.log.impl.ExecutionLogImpl -> JBPM_EXELOG
            2005-12-29 17:01:37,416 INFO [STDOUT] 17:01:37,416 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:37,416 INFO [STDOUT] 17:01:37,416 debug [Binder] Mapped property: invocationLog -> invocationLog, type: org.jbpm.model.log.impl.InvocationLogImpl
            2005-12-29 17:01:37,416 INFO [STDOUT] 17:01:37,416 debug [Binder] Mapped property: index -> index_, type: integer
            2005-12-29 17:01:37,426 INFO [STDOUT] 17:01:37,426 info [Binder] Mapping subclass: org.jbpm.model.log.impl.VariableCreationLogImpl -> JBPM_EXELOG
            2005-12-29 17:01:37,426 INFO [STDOUT] 17:01:37,426 debug [Binder] Mapped property: variableInstance -> varinst1, type: org.jbpm.model.execution.impl.VariableInstanceImpl
            2005-12-29 17:01:37,426 INFO [STDOUT] 17:01:37,426 info [Binder] Mapping subclass: org.jbpm.model.log.impl.VariableUpdateLogImpl -> JBPM_EXELOG
            2005-12-29 17:01:37,426 INFO [STDOUT] 17:01:37,426 debug [Binder] Mapped property: oldValueText -> text1, type: string
            2005-12-29 17:01:37,426 INFO [STDOUT] 17:01:37,426 debug [Binder] Mapped property: newValueText -> text2, type: string
            2005-12-29 17:01:37,426 INFO [STDOUT] 17:01:37,426 debug [Binder] Mapped property: variableInstance -> varinst2, type: org.jbpm.model.execution.impl.VariableInstanceImpl
            2005-12-29 17:01:37,426 INFO [STDOUT] 17:01:37,426 info [Binder] Mapping subclass: org.jbpm.model.log.impl.ActionLogImpl -> JBPM_EXELOG
            2005-12-29 17:01:37,426 INFO [STDOUT] 17:01:37,426 debug [Binder] Mapped property: action -> action_, type: org.jbpm.model.definition.impl.ActionImpl
            2005-12-29 17:01:37,426 INFO [STDOUT] 17:01:37,426 info [Binder] Mapping subclass: org.jbpm.model.log.impl.StateLogImpl -> JBPM_EXELOG
            2005-12-29 17:01:37,426 INFO [STDOUT] 17:01:37,426 debug [Binder] Mapped property: previous -> previous, type: org.jbpm.model.log.impl.StateLogImpl
            2005-12-29 17:01:37,426 INFO [STDOUT] 17:01:37,426 debug [Binder] Mapped property: actorId -> text1, type: string
            2005-12-29 17:01:37,426 INFO [STDOUT] 17:01:37,426 debug [Binder] Mapped property: state -> state, type: org.jbpm.model.definition.impl.StateImpl
            2005-12-29 17:01:37,426 INFO [STDOUT] 17:01:37,426 info [Binder] Mapping subclass: org.jbpm.model.log.impl.EndOfTokenLogImpl -> JBPM_EXELOG
            2005-12-29 17:01:37,426 INFO [STDOUT] 17:01:37,426 debug [Binder] Mapped property: token -> token, type: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,446 INFO [STDOUT] 17:01:37,446 info [Binder] Mapping subclass: org.jbpm.model.log.impl.MessageLogImpl -> JBPM_EXELOG
            2005-12-29 17:01:37,456 INFO [STDOUT] 17:01:37,456 debug [Binder] Mapped property: level -> int_one, type: org.jbpm.util.log.Level
            2005-12-29 17:01:37,456 INFO [STDOUT] 17:01:37,456 debug [Binder] Mapped property: message -> text2, type: string
            2005-12-29 17:01:37,466 INFO [STDOUT] 17:01:37,466 info [Binder] Mapping subclass: org.jbpm.model.log.impl.SubTokenLogImpl -> JBPM_EXELOG
            2005-12-29 17:01:37,466 INFO [STDOUT] 17:01:37,466 debug [Binder] Mapped property: subToken -> subToken, type: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,486 INFO [STDOUT] 17:01:37,486 info [Configuration] Mapping resource: org/jbpm/model/scheduler/impl/JobImpl.hbm.xml
            2005-12-29 17:01:37,486 INFO [STDOUT] 17:01:37,486 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:37,486 INFO [STDOUT] 17:01:37,486 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 info [Binder] Mapping class: org.jbpm.model.scheduler.impl.JobImpl -> JBPM_JOB
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 debug [Binder] Mapped property: actionDelegation -> actionDelegation, type: org.jbpm.model.definition.impl.DelegationImpl
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 debug [Binder] Mapped property: token -> token, type: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 debug [Binder] Mapped property: processInstance -> processInstance, type: org.jbpm.model.execution.impl.ProcessInstanceImpl
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 debug [Binder] Mapped property: dueDate -> dueDate, type: timestamp
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 debug [Binder] Mapped property: repeat -> repeat_, type: long
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 debug [Binder] Mapped property: reference -> reference, type: string
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 info [Configuration] processing one-to-many association mappings
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 debug [Binder] Second pass for collection: org.jbpm.model.definition.impl.DefinitionImpl.actions
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 info [Binder] Mapping collection: org.jbpm.model.definition.impl.DefinitionImpl.actions -> JBPM_ACTION
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 debug [Binder] Mapped collection key: element, index: index_, one-to-many: org.jbpm.model.definition.impl.ActionImpl
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 debug [Binder] Second pass for collection: org.jbpm.model.definition.impl.DefinitionImpl.swimlanes
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 info [Binder] Mapping collection: org.jbpm.model.definition.impl.DefinitionImpl.swimlanes -> JBPM_SWIMLANE
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 debug [Binder] Mapped collection key: definition, index: name, one-to-many: org.jbpm.model.definition.impl.SwimlaneImpl
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 debug [Binder] Second pass for collection: org.jbpm.model.definition.impl.DefinitionImpl.types
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 info [Binder] Mapping collection: org.jbpm.model.definition.impl.DefinitionImpl.types -> JBPM_TYPE
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 debug [Binder] Mapped collection key: definition, index: index_, one-to-many: org.jbpm.model.definition.impl.TypeImpl
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 debug [Binder] Second pass for collection: org.jbpm.model.definition.impl.DefinitionImpl.nodes
            2005-12-29 17:01:37,496 INFO [STDOUT] 17:01:37,496 info [Binder] Mapping collection: org.jbpm.model.definition.impl.DefinitionImpl.nodes -> JBPM_NODE
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Mapped collection key: definition, index: name, one-to-many: org.jbpm.model.definition.impl.NodeImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Second pass for collection: org.jbpm.model.definition.impl.DefinitionImpl.variables
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 info [Binder] Mapping collection: org.jbpm.model.definition.impl.DefinitionImpl.variables -> JBPM_VARIABLE
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Mapped collection key: definition, index: name, one-to-many: org.jbpm.model.definition.impl.VariableImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Second pass for collection: org.jbpm.model.definition.impl.NodeImpl.actions
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 info [Binder] Mapping collection: org.jbpm.model.definition.impl.NodeImpl.actions -> JBPM_ACTION
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Mapped collection key: element, index: index_, one-to-many: org.jbpm.model.definition.impl.ActionImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Second pass for collection: org.jbpm.model.definition.impl.NodeImpl.leavingTransitions
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 info [Binder] Mapping collection: org.jbpm.model.definition.impl.NodeImpl.leavingTransitions -> JBPM_TRANSITION
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Mapped collection key: from_, one-to-many: org.jbpm.model.definition.impl.TransitionImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Second pass for collection: org.jbpm.model.definition.impl.TransitionImpl.actions
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 info [Binder] Mapping collection: org.jbpm.model.definition.impl.TransitionImpl.actions -> JBPM_ACTION
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Mapped collection key: element, index: index_, one-to-many: org.jbpm.model.definition.impl.ActionImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Second pass for collection: org.jbpm.model.definition.impl.TypeImpl.variables
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 info [Binder] Mapping collection: org.jbpm.model.definition.impl.TypeImpl.variables -> JBPM_VARIABLE
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Mapped collection key: type_, index: name, one-to-many: org.jbpm.model.definition.impl.VariableImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Second pass for collection: org.jbpm.model.execution.impl.TokenImpl.children
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 info [Binder] Mapping collection: org.jbpm.model.execution.impl.TokenImpl.children -> JBPM_TOKEN
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Mapped collection key: parent, index: name, one-to-many: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Second pass for collection: org.jbpm.model.execution.impl.TokenImpl.variableInstances
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 info [Binder] Mapping collection: org.jbpm.model.execution.impl.TokenImpl.variableInstances -> JBPM_VARIABLEINSTANCE
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Mapped collection key: token, index: name, one-to-many: org.jbpm.model.execution.impl.VariableInstanceImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Second pass for collection: org.jbpm.model.execution.impl.TokenImpl.invocationLogs
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 info [Binder] Mapping collection: org.jbpm.model.execution.impl.TokenImpl.invocationLogs -> JBPM_INVOKELOG
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Mapped collection key: token, one-to-many: org.jbpm.model.log.impl.InvocationLogImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Second pass for collection: org.jbpm.model.execution.impl.VariableInstanceImpl.updateLogs
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 info [Binder] Mapping collection: org.jbpm.model.execution.impl.VariableInstanceImpl.updateLogs -> JBPM_EXELOG
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Mapped collection key: variableInstance, one-to-many: org.jbpm.model.log.impl.VariableUpdateLogImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Second pass for collection: org.jbpm.model.log.impl.InvocationLogImpl.executionLogs
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 info [Binder] Mapping collection: org.jbpm.model.log.impl.InvocationLogImpl.executionLogs -> JBPM_EXELOG
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Binder] Mapped collection key: invocationLog, index: index_, one-to-many: org.jbpm.model.log.impl.ExecutionLogImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 info [Configuration] processing one-to-one association property references
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 info [Configuration] processing foreign key constraints
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.EndStateImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.DefinitionImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.StartStateImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.DefinitionImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.TypeImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.DefinitionImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.execution.impl.ProcessInstanceImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.DelegationImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.TypeImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.SwimlaneImpl
            2005-12-29 17:01:37,506 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.ForkImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,506 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.DelegationImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.DefinitionImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.JoinImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.DelegationImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.DefinitionImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.log.impl.StateLogImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.StateImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.execution.impl.ProcessInstanceImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.execution.impl.ProcessInstanceImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.JoinImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.DefinitionImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.NodeImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.DefinitionImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.NodeImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.DefinitionImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.DelegationImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.DelegationImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.execution.impl.VariableInstanceImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.StateImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.log.impl.StateLogImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.execution.impl.TokenImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.log.impl.InvocationLogImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.definition.impl.ActionImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.execution.impl.VariableInstanceImpl
            2005-12-29 17:01:37,516 INFO [STDOUT] 17:01:37,516 debug [Configuration] resolving reference to class: org.jbpm.model.execution.impl.VariableInstanceImpl
            2005-12-29 17:01:37,646 INFO [STDOUT] 17:01:37,646 info [Dialect] Using dialect: net.sf.hibernate.dialect.Sybase11_9_2Dialect
            2005-12-29 17:01:37,646 INFO [STDOUT] 17:01:37,646 info [SettingsFactory] Use outer join fetching: true
            2005-12-29 17:01:37,676 INFO [STDOUT] 17:01:37,676 info [NamingHelper] JNDI InitialContext properties:{}
            2005-12-29 17:01:37,676 INFO [STDOUT] 17:01:37,676 info [DatasourceConnectionProvider] Using datasource: java:/TPAODS
            2005-12-29 17:01:37,686 INFO [STDOUT] 17:01:37,686 info [TransactionFactoryFactory] Transaction strategy: net.sf.hibernate.transaction.JTATransactionFactory
            2005-12-29 17:01:37,696 INFO [STDOUT] 17:01:37,696 info [NamingHelper] JNDI InitialContext properties:{}
            2005-12-29 17:01:37,706 INFO [STDOUT] 17:01:37,706 info [TransactionManagerLookupFactory] instantiating TransactionManagerLookup: net.sf.hibernate.transaction.JBossTransactionManagerLookup
            2005-12-29 17:01:37,726 INFO [STDOUT] 17:01:37,726 info [TransactionManagerLookupFactory] instantiated TransactionManagerLookup
            2005-12-29 17:01:37,726 INFO [STDOUT] 17:01:37,726 info [NamingHelper] JNDI InitialContext properties:{}
            2005-12-29 17:01:37,726 INFO [STDOUT] 17:01:37,726 info [TransactionManagerLookupFactory] instantiating TransactionManagerLookup: net.sf.hibernate.transaction.JBossTransactionManagerLookup
            2005-12-29 17:01:37,726 INFO [STDOUT] 17:01:37,726 info [TransactionManagerLookupFactory] instantiated TransactionManagerLookup
            2005-12-29 17:01:37,746 INFO [STDOUT] 17:01:37,746 info [SettingsFactory] Use scrollable result sets: true
            2005-12-29 17:01:37,746 INFO [STDOUT] 17:01:37,746 info [SettingsFactory] Use JDBC3 getGeneratedKeys(): false
            2005-12-29 17:01:37,746 INFO [STDOUT] 17:01:37,746 info [SettingsFactory] Optimize cache for minimal puts: false
            2005-12-29 17:01:37,746 INFO [STDOUT] 17:01:37,746 info [SettingsFactory] Query language substitutions: {}
            2005-12-29 17:01:37,746 INFO [STDOUT] 17:01:37,746 info [SettingsFactory] cache provider: net.sf.hibernate.cache.EhCacheProvider
            2005-12-29 17:01:37,756 INFO [STDOUT] 17:01:37,756 info [Configuration] instantiating and configuring caches
            2005-12-29 17:01:38,147 INFO [STDOUT] 17:01:38,147 info [SessionFactoryImpl] building session factory
            2005-12-29 17:01:38,157 INFO [STDOUT] 17:01:38,147 debug [SessionFactoryImpl] instantiating session factory with properties: {java.vendor=Sun Microsystems Inc., jbpm.log.stdout=on, catalina.base=C:\Proje\JBoss\server\default, jbpm.apply.transactions=no, jbpm.persistence.session.factory=hibernate, catalina.useNaming=false, os.name=Windows XP, sun.boot.class.path=C:\Proje\Eclipse\jre\lib\rt.jar;C:\Proje\Eclipse\jre\lib\i18n.jar;C:\Proje\Eclipse\jre\lib\sunrsasign.jar;C:\Proje\Eclipse\jre\lib\jsse.jar;C:\Proje\Eclipse\jre\lib\jce.jar;C:\Proje\Eclipse\jre\lib\charsets.jar;C:\Proje\Eclipse\jre\classes, sun.java2d.fontpath=, java.vm.specification.vendor=Sun Microsystems Inc., jbpm.id.generator.node.id=3, java.runtime.version=1.4.2_06-b03, user.name=ayca, shared.loader=${catalina.base}/shared/classes,${catalina.base}/shared/lib/*.jar, jboss.bind.address=0.0.0.0, hibernate.session_factory_name=hibernate/HibernateFactory, hibernate.connection.release_mode=after_statement , jbpm.id.generator.block.size=100, jbpm.log.default=debug, jboss.home.dir=C:\Proje\JBoss, user.language=tr, java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, sun.boot.library.path=C:\Proje\Eclipse\jre\bin, jboss.home.url=file:/C:/Proje/JBoss/, jbpm.scheduler.wait.period=5000, jbpm.execute.actions=true, java.version=1.4.2_06, version=2.4.1, user.timezone=Europe/Athens, jboss.server.home.dir=C:\Proje\JBoss\server\default, sun.arch.data.model=32, java.endorsed.dirs=C:\Proje\Eclipse\jre\lib\endorsed, jboss.server.home.url=file:/C:/Proje/JBoss/server/default/, sun.cpu.isalist=pentium i486 i386, file.encoding.pkg=sun.io, jbpm.id.generator=default, package.access=sun.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper.,sun.beans., file.separator=\, java.specification.name=Java Platform API Specification, hibernate.cglib.use_reflection_optimizer=true, java.class.version=48.0, jboss.server.config.url=file:/C:/Proje/JBoss/server/default/conf/, user.country=TR, java.home=C:\Proje\Eclipse\jre, java.vm.info=mixed mode, jboss.lib.url=file:/C:/Proje/JBoss/lib/, jbpm.log.package.org.jbpm=debug, os.version=5.1, hibernate.transaction.factory_class=net.sf.hibernate.transaction.JTATransactionFactory, hibernate.connection.datasource=java:/TPAODS, path.separator=;, java.vm.version=1.4.2_06-b03, jbpm.create.tables=never, java.util.prefs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, user.variant=, vendor-url=http://xml.apache.org/xalan-j, java.protocol.handler.pkgs=org.jboss.net.protocol, java.awt.printerjob=sun.awt.windows.WPrinterJob, vendor=Apache Software Foundation, jbpm.validate.xml=true, sun.io.unicode.encoding=UnicodeLittle, awt.toolkit=sun.awt.windows.WToolkit, package.definition=sun.,java.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper., jboss.server.temp.dir=C:\Proje\JBoss\server\default\tmp, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces, user.home=C:\Documents and Settings\ayca, java.rmi.server.RMIClassLoaderSpi=org.jboss.system.JBossRMIClassLoader, java.specification.vendor=Sun Microsystems Inc., org.xml.sax.driver=org.apache.crimson.parser.XMLReaderImpl, jboss.home=C:\Proje\JBoss, java.library.path=C:\Proje\Eclipse\jre\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\sybase\OCS-12_5\lib3p;C:\sybase\OCS-12_5\dll;C:\sybase\OCS-12_5\bin;C:\sybase\SQLRemote\dll;C:\sybase\RPL-12_5\bin;C:\sybase\JS-12_5\bin;C:\sybase\ASE-12_5\dll;C:\sybase\ASE-12_5\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\Sybase\SQL Anywhere 9\win32;C:\Program Files\Sybase\Shared\win32;C:\Program Files\Sybase\SQL Anywhere 9\drivers;C:\Program Files\Sybase\Shared\Sybase Central 4.3\win32;C:\Program Files\Gemplus\GemSafe Libraries User\Bin;, java.vendor.url=http://java.sun.com/, hibernate.connection.driver_class=com.sybase.jdbc2.jdbc.SybDriver, jbpm.service.factory=default, java.vm.vendor=Sun Microsystems Inc., hibernate.dialect=net.sf.hibernate.dialect.Sybase11_9_2Dialect, jbpm.create.tables.log=false, common.loader=${catalina.home}/common/classes,${catalina.home}/common/endorsed/*.jar,${catalina.home}/common/lib/*.jar, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=C:\Proje\JBoss\bin\run.jar;C:\Proje\Eclipse\lib\tools.jar, org.apache.commons.logging.LogFactory=org.jbpm.util.log.JbpmLogFactory, jbossmx.loader.repository.class=org.jboss.mx.loading.UnifiedLoaderRepository3, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, catalina.home=C:\Proje\JBoss\server\default, sun.cpu.endian=little, sun.os.patch.level=Service Pack 2, jboss.server.lib.url=file:/C:/Proje/JBoss/server/default/lib/, java.rmi.server.codebase=http://tpao-mobil5:8083/, java.io.tmpdir=C:\DOCUME~1\ayca\LOCALS~1\Temp\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, server.loader=${catalina.home}/server/classes,${catalina.home}/server/lib/*.jar, jboss.server.data.dir=C:\Proje\JBoss\server\default\data, os.arch=x86, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.ext.dirs=C:\Proje\Eclipse\jre\lib\ext, user.dir=C:\Proje\JBoss\bin, line.separator=
            , java.vm.name=Java HotSpot(TM) Client VM, jboss.server.base.dir=C:\Proje\JBoss\server, jbpm.file.mgr=database, jboss.server.base.url=file:/C:/Proje/JBoss/server/, javax.management.builder.initial=org.jboss.mx.server.MBeanServerBuilderImpl, file.encoding=Cp1254, jbpm.create.tables.query=SELECT ID FROM JBPM_DEFINITION WHERE ID = 1, catalina.ext.dirs=C:\Proje\JBoss\server\default\lib, hibernate.transaction.manager_lookup_class=net.sf.hibernate.transaction.JBossTransactionManagerLookup, java.specification.version=1.4, jbpm.id.generator.configuration=same, jboss.server.name=default, hibernate.show_sql=true }
            2005-12-29 17:01:38,617 INFO [STDOUT] 17:01:38,617 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.execution.impl.TokenImpl, BulkBeanException: Cannot find specified property (property setProcessInstance)
            2005-12-29 17:01:38,688 INFO [STDOUT] 17:01:38,688 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.definition.impl.EndStateImpl, BulkBeanException: Cannot find specified property (property setDefinition)
            2005-12-29 17:01:38,698 INFO [STDOUT] 17:01:38,698 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.definition.impl.TransitionImpl, BulkBeanException: Cannot find specified property (property setDefinition)
            2005-12-29 17:01:38,718 INFO [STDOUT] 17:01:38,718 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.log.impl.InvocationLogImpl, BulkBeanException: Cannot find specified property (property setToken)
            2005-12-29 17:01:38,728 INFO [STDOUT] 17:01:38,728 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.definition.impl.ActionImpl, BulkBeanException: Cannot find specified property (property setElement)
            2005-12-29 17:01:38,738 INFO [STDOUT] 17:01:38,738 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.definition.impl.StateImpl, BulkBeanException: Cannot find specified property (property setDefinition)
            2005-12-29 17:01:38,748 INFO [STDOUT] 17:01:38,748 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.execution.impl.VariableInstanceImpl, BulkBeanException: Cannot find specified property (property setType)
            2005-12-29 17:01:38,778 INFO [STDOUT] 17:01:38,778 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.definition.impl.DecisionImpl, BulkBeanException: Cannot find specified property (property setDefinition)
            2005-12-29 17:01:38,828 INFO [STDOUT] 17:01:38,828 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.definition.impl.SwimlaneImpl, BulkBeanException: Cannot find specified property (property setDefinition)
            2005-12-29 17:01:38,838 INFO [STDOUT] 17:01:38,838 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.definition.impl.ForkImpl, BulkBeanException: Cannot find specified property (property setCorrespondingJoin)
            2005-12-29 17:01:38,858 INFO [STDOUT] 17:01:38,858 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.definition.impl.StartStateImpl, BulkBeanException: Cannot find specified property (property setDefinition)
            2005-12-29 17:01:38,868 INFO [STDOUT] 17:01:38,868 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.log.impl.StateLogImpl, BulkBeanException: Cannot find specified property (property setPrevious)
            2005-12-29 17:01:38,898 INFO [STDOUT] 17:01:38,898 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.definition.impl.DefinitionImpl, BulkBeanException: Cannot find specified property (property setDefinition)
            2005-12-29 17:01:38,908 INFO [STDOUT] 17:01:38,908 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.execution.impl.ProcessInstanceImpl, BulkBeanException: Cannot find specified property (property setDefinition)
            2005-12-29 17:01:38,918 INFO [STDOUT] 17:01:38,918 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.definition.impl.TypeImpl, BulkBeanException: Cannot find specified property (property setDefinition)
            2005-12-29 17:01:38,938 INFO [STDOUT] 17:01:38,938 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.log.impl.ActionLogImpl, BulkBeanException: Cannot find specified property (property setAction)
            2005-12-29 17:01:38,958 INFO [STDOUT] 17:01:38,958 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.definition.impl.DelegationImpl, BulkBeanException: Cannot find specified property (property setDefinition)
            2005-12-29 17:01:38,978 INFO [STDOUT] 17:01:38,978 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.definition.impl.MilestoneImpl, BulkBeanException: Cannot find specified property (property setDefinition)
            2005-12-29 17:01:38,998 INFO [STDOUT] 17:01:38,998 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.definition.impl.JoinImpl, BulkBeanException: Cannot find specified property (property setDefinition)
            2005-12-29 17:01:39,008 INFO [STDOUT] 17:01:39,008 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.definition.impl.NodeImpl, BulkBeanException: Cannot find specified property (property setDefinition)
            2005-12-29 17:01:39,028 INFO [STDOUT] 17:01:39,028 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.definition.impl.ProcessStateImpl, BulkBeanException: Cannot find specified property (property setDefinition)
            2005-12-29 17:01:39,038 INFO [STDOUT] 17:01:39,038 info [ReflectHelper] reflection optimizer disabled for: org.jbpm.model.log.impl.EndOfTokenLogImpl, BulkBeanException: Cannot find specified property (property setToken)
            2005-12-29 17:01:39,238 INFO [STDOUT] 17:01:39,238 debug [SessionFactoryObjectFactory] initializing class SessionFactoryObjectFactory
            2005-12-29 17:01:39,258 INFO [STDOUT] 17:01:39,258 debug [SessionFactoryObjectFactory] registered: 8a81875c087709cf01087709d51c0000 (hibernate/HibernateFactory)
            2005-12-29 17:01:39,258 INFO [STDOUT] 17:01:39,258 info [SessionFactoryObjectFactory] Factory name: hibernate/HibernateFactory
            2005-12-29 17:01:39,258 INFO [STDOUT] 17:01:39,258 info [NamingHelper] JNDI InitialContext properties:{}
            2005-12-29 17:01:39,258 INFO [STDOUT] 17:01:39,258 debug [SessionFactoryImpl] Returning a Reference to the SessionFactory
            2005-12-29 17:01:39,258 INFO [STDOUT] 17:01:39,258 info [NamingHelper] Creating subcontext: hibernate
            2005-12-29 17:01:39,258 INFO [STDOUT] 17:01:39,258 debug [SessionFactoryImpl] Returning a Reference to the SessionFactory
            2005-12-29 17:01:39,258 INFO [STDOUT] 17:01:39,258 debug [NamingHelper] Bound name: hibernate/HibernateFactory
            2005-12-29 17:01:39,258 INFO [STDOUT] 17:01:39,258 info [SessionFactoryObjectFactory] Bound factory to JNDI name: hibernate/HibernateFactory
            2005-12-29 17:01:39,258 INFO [STDOUT] 17:01:39,258 warn [SessionFactoryObjectFactory] InitialContext did not implement EventContext
            2005-12-29 17:01:39,258 INFO [STDOUT] 17:01:39,258 debug [SessionFactoryImpl] instantiated session factory
            2005-12-29 17:01:39,258 INFO [STDOUT] 17:01:39,258 debug [SessionFactoryImpl] obtaining JTA TransactionManager
            2005-12-29 17:01:39,258 INFO [STDOUT] 17:01:39,258 info [NamingHelper] JNDI InitialContext properties:{}
            2005-12-29 17:01:39,358 INFO [STDOUT] 17:01:39,358 debug [SessionImpl] opened session
            2005-12-29 17:01:39,358 INFO [STDOUT] 17:01:39,358 debug [HibernateSessionFactory] created the hibernate session factory
            2005-12-29 17:01:39,369 INFO [STDOUT] 17:01:39,358 debug [JbpmConfiguration] for key 'jbpm.persistence.session.factory', an object of type 'org.jbpm.persistence.hibernate.HibernateSessionFactory' was instantiated
            2005-12-29 17:01:39,389 INFO [STDOUT] 17:01:39,389 info [Configuration] Mapping resource: org/jbpm/persistence/hibernate/SequenceBlock.hbm.xml
            2005-12-29 17:01:39,389 INFO [STDOUT] 17:01:39,389 debug [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
            2005-12-29 17:01:39,389 INFO [STDOUT] 17:01:39,389 debug [DTDEntityResolver] found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
            2005-12-29 17:01:39,399 INFO [STDOUT] 17:01:39,399 info [Binder] Mapping class: org.jbpm.persistence.hibernate.SequenceBlock -> JBPM_SEQUENCEBLOCK
            2005-12-29 17:01:39,399 INFO [STDOUT] 17:01:39,399 debug [Binder] Mapped property: id -> id, type: long
            2005-12-29 17:01:39,399 INFO [STDOUT] 17:01:39,399 debug [Binder] Mapped property: nextId -> nextId, type: long
            2005-12-29 17:01:39,399 INFO [STDOUT] 17:01:39,399 info [Configuration] processing one-to-many association mappings
            2005-12-29 17:01:39,399 INFO [STDOUT] 17:01:39,399 info [Configuration] processing one-to-one association property references
            2005-12-29 17:01:39,399 INFO [STDOUT] 17:01:39,399 info [Configuration] processing foreign key constraints
            2005-12-29 17:01:39,399 INFO [STDOUT] 17:01:39,399 info [Dialect] Using dialect: net.sf.hibernate.dialect.Sybase11_9_2Dialect
            2005-12-29 17:01:39,399 INFO [STDOUT] 17:01:39,399 info [SettingsFactory] Use outer join fetching: true
            2005-12-29 17:01:39,399 INFO [STDOUT] 17:01:39,399 info [NamingHelper] JNDI InitialContext properties:{}
            2005-12-29 17:01:39,409 INFO [STDOUT] 17:01:39,409 info [DatasourceConnectionProvider] Using datasource: java:/TPAODS
            2005-12-29 17:01:39,409 INFO [STDOUT] 17:01:39,409 info [TransactionFactoryFactory] Transaction strategy: net.sf.hibernate.transaction.JTATransactionFactory
            2005-12-29 17:01:39,409 INFO [STDOUT] 17:01:39,409 info [NamingHelper] JNDI InitialContext properties:{}
            2005-12-29 17:01:39,409 INFO [STDOUT] 17:01:39,409 info [TransactionManagerLookupFactory] instantiating TransactionManagerLookup: net.sf.hibernate.transaction.JBossTransactionManagerLookup
            2005-12-29 17:01:39,409 INFO [STDOUT] 17:01:39,409 info [TransactionManagerLookupFactory] instantiated TransactionManagerLookup
            2005-12-29 17:01:39,409 INFO [STDOUT] 17:01:39,409 info [NamingHelper] JNDI InitialContext properties:{}
            2005-12-29 17:01:39,409 INFO [STDOUT] 17:01:39,409 info [TransactionManagerLookupFactory] instantiating TransactionManagerLookup: net.sf.hibernate.transaction.JBossTransactionManagerLookup
            2005-12-29 17:01:39,409 INFO [STDOUT] 17:01:39,409 info [TransactionManagerLookupFactory] instantiated TransactionManagerLookup
            2005-12-29 17:01:39,409 INFO [STDOUT] 17:01:39,409 info [SettingsFactory] Use scrollable result sets: true
            2005-12-29 17:01:39,409 INFO [STDOUT] 17:01:39,409 info [SettingsFactory] Use JDBC3 getGeneratedKeys(): false
            2005-12-29 17:01:39,409 INFO [STDOUT] 17:01:39,409 info [SettingsFactory] Optimize cache for minimal puts: false
            2005-12-29 17:01:39,409 INFO [STDOUT] 17:01:39,409 info [SettingsFactory] Query language substitutions: {}
            2005-12-29 17:01:39,409 INFO [STDOUT] 17:01:39,409 info [SettingsFactory] cache provider: net.sf.hibernate.cache.EhCacheProvider
            2005-12-29 17:01:39,409 INFO [STDOUT] 17:01:39,409 info [Configuration] instantiating and configuring caches
            2005-12-29 17:01:39,419 INFO [STDOUT] 17:01:39,419 info [SessionFactoryImpl] building session factory
            2005-12-29 17:01:39,419 INFO [STDOUT] 17:01:39,419 debug [SessionFactoryImpl] instantiating session factory with properties: {java.vendor=Sun Microsystems Inc., jbpm.log.stdout=on, catalina.base=C:\Proje\JBoss\server\default, jbpm.apply.transactions=no, jbpm.persistence.session.factory=hibernate, catalina.useNaming=false, os.name=Windows XP, sun.boot.class.path=C:\Proje\Eclipse\jre\lib\rt.jar;C:\Proje\Eclipse\jre\lib\i18n.jar;C:\Proje\Eclipse\jre\lib\sunrsasign.jar;C:\Proje\Eclipse\jre\lib\jsse.jar;C:\Proje\Eclipse\jre\lib\jce.jar;C:\Proje\Eclipse\jre\lib\charsets.jar;C:\Proje\Eclipse\jre\classes, sun.java2d.fontpath=, java.vm.specification.vendor=Sun Microsystems Inc., jbpm.id.generator.node.id=3, java.runtime.version=1.4.2_06-b03, user.name=ayca, shared.loader=${catalina.base}/shared/classes,${catalina.base}/shared/lib/*.jar, jboss.bind.address


            • 3. Re: executionService.close() gives error all the time

               

              =0.0.0.0, hibernate.session_factory_name=hibernate/HibernateFactory, hibernate.connection.release_mode=after_statement , jbpm.id.generator.block.size=100, jbpm.log.default=debug, jboss.home.dir=C:\Proje\JBoss, user.language=tr, java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, sun.boot.library.path=C:\Proje\Eclipse\jre\bin, jboss.home.url=file:/C:/Proje/JBoss/, jbpm.scheduler.wait.period=5000, jbpm.execute.actions=true, java.version=1.4.2_06, version=2.4.1, user.timezone=Europe/Athens, jboss.server.home.dir=C:\Proje\JBoss\server\default, sun.arch.data.model=32, java.endorsed.dirs=C:\Proje\Eclipse\jre\lib\endorsed, jboss.server.home.url=file:/C:/Proje/JBoss/server/default/, sun.cpu.isalist=pentium i486 i386, file.encoding.pkg=sun.io, jbpm.id.generator=default, package.access=sun.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper.,sun.beans., file.separator=\, java.specification.name=Java Platform API Specification, hibernate.cglib.use_reflection_optimizer=true, java.class.version=48.0, jboss.server.config.url=file:/C:/Proje/JBoss/server/default/conf/, user.country=TR, java.home=C:\Proje\Eclipse\jre, java.vm.info=mixed mode, jboss.lib.url=file:/C:/Proje/JBoss/lib/, jbpm.log.package.org.jbpm=debug, os.version=5.1, hibernate.transaction.factory_class=net.sf.hibernate.transaction.JTATransactionFactory, hibernate.connection.datasource=java:/TPAODS, path.separator=;, java.vm.version=1.4.2_06-b03, jbpm.create.tables=never, java.util.prefs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, user.variant=, vendor-url=http://xml.apache.org/xalan-j, java.protocol.handler.pkgs=org.jboss.net.protocol, java.awt.printerjob=sun.awt.windows.WPrinterJob, vendor=Apache Software Foundation, jbpm.validate.xml=true, sun.io.unicode.encoding=UnicodeLittle, awt.toolkit=sun.awt.windows.WToolkit, package.definition=sun.,java.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper., jboss.server.temp.dir=C:\Proje\JBoss\server\default\tmp, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces, user.home=C:\Documents and Settings\ayca, java.rmi.server.RMIClassLoaderSpi=org.jboss.system.JBossRMIClassLoader, java.specification.vendor=Sun Microsystems Inc., org.xml.sax.driver=org.apache.crimson.parser.XMLReaderImpl, jboss.home=C:\Proje\JBoss, java.library.path=C:\Proje\Eclipse\jre\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\sybase\OCS-12_5\lib3p;C:\sybase\OCS-12_5\dll;C:\sybase\OCS-12_5\bin;C:\sybase\SQLRemote\dll;C:\sybase\RPL-12_5\bin;C:\sybase\JS-12_5\bin;C:\sybase\ASE-12_5\dll;C:\sybase\ASE-12_5\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\Sybase\SQL Anywhere 9\win32;C:\Program Files\Sybase\Shared\win32;C:\Program Files\Sybase\SQL Anywhere 9\drivers;C:\Program Files\Sybase\Shared\Sybase Central 4.3\win32;C:\Program Files\Gemplus\GemSafe Libraries User\Bin;, java.vendor.url=http://java.sun.com/, hibernate.connection.driver_class=com.sybase.jdbc2.jdbc.SybDriver, jbpm.service.factory=default, java.vm.vendor=Sun Microsystems Inc., hibernate.dialect=net.sf.hibernate.dialect.Sybase11_9_2Dialect, jbpm.create.tables.log=false, common.loader=${catalina.home}/common/classes,${catalina.home}/common/endorsed/*.jar,${catalina.home}/common/lib/*.jar, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=C:\Proje\JBoss\bin\run.jar;C:\Proje\Eclipse\lib\tools.jar, org.apache.commons.logging.LogFactory=org.jbpm.util.log.JbpmLogFactory, jbossmx.loader.repository.class=org.jboss.mx.loading.UnifiedLoaderRepository3, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, catalina.home=C:\Proje\JBoss\server\default, sun.cpu.endian=little, sun.os.patch.level=Service Pack 2, jboss.server.lib.url=file:/C:/Proje/JBoss/server/default/lib/, java.rmi.server.codebase=http://tpao-mobil5:8083/, java.io.tmpdir=C:\DOCUME~1\ayca\LOCALS~1\Temp\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, server.loader=${catalina.home}/server/classes,${catalina.home}/server/lib/*.jar, jboss.server.data.dir=C:\Proje\JBoss\server\default\data, os.arch=x86, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.ext.dirs=C:\Proje\Eclipse\jre\lib\ext, user.dir=C:\Proje\JBoss\bin, line.separator=
              , java.vm.name=Java HotSpot(TM) Client VM, jboss.server.base.dir=C:\Proje\JBoss\server, jbpm.file.mgr=database, jboss.server.base.url=file:/C:/Proje/JBoss/server/, javax.management.builder.initial=org.jboss.mx.server.MBeanServerBuilderImpl, file.encoding=Cp1254, jbpm.create.tables.query=SELECT ID FROM JBPM_DEFINITION WHERE ID = 1, catalina.ext.dirs=C:\Proje\JBoss\server\default\lib, hibernate.transaction.manager_lookup_class=net.sf.hibernate.transaction.JBossTransactionManagerLookup, java.specification.version=1.4, jbpm.id.generator.configuration=same, jboss.server.name=default, hibernate.show_sql=true }
              2005-12-29 17:01:39,439 INFO [STDOUT] 17:01:39,439 debug [SessionFactoryObjectFactory] registered: 8a81875c087709cf01087709d5ef0001 (hibernate/HibernateFactory)
              2005-12-29 17:01:39,439 INFO [STDOUT] 17:01:39,439 info [SessionFactoryObjectFactory] Factory name: hibernate/HibernateFactory
              2005-12-29 17:01:39,439 INFO [STDOUT] 17:01:39,439 info [NamingHelper] JNDI InitialContext properties:{}
              2005-12-29 17:01:39,439 INFO [STDOUT] 17:01:39,439 debug [SessionFactoryImpl] Returning a Reference to the SessionFactory
              2005-12-29 17:01:39,439 INFO [STDOUT] 17:01:39,439 debug [NamingHelper] Bound name: hibernate/HibernateFactory
              2005-12-29 17:01:39,439 INFO [STDOUT] 17:01:39,439 info [SessionFactoryObjectFactory] Bound factory to JNDI name: hibernate/HibernateFactory
              2005-12-29 17:01:39,439 INFO [STDOUT] 17:01:39,439 warn [SessionFactoryObjectFactory] InitialContext did not implement EventContext
              2005-12-29 17:01:39,439 INFO [STDOUT] 17:01:39,439 debug [SessionFactoryImpl] instantiated session factory
              2005-12-29 17:01:39,439 INFO [STDOUT] 17:01:39,439 debug [SessionFactoryImpl] obtaining JTA TransactionManager
              2005-12-29 17:01:39,439 INFO [STDOUT] 17:01:39,439 info [NamingHelper] JNDI InitialContext properties:{}
              2005-12-29 17:01:39,439 INFO [STDOUT] 17:01:39,439 debug [SequenceBlockIdGenerator] created the hibernate session factory for the id generator
              2005-12-29 17:01:39,439 INFO [STDOUT] 17:01:39,439 debug [JbpmConfiguration] for key 'jbpm.id.generator', an object of type 'org.jbpm.persistence.hibernate.SequenceBlockIdGenerator' was instantiated
              2005-12-29 17:01:39,459 INFO [STDOUT] 17:01:39,459 debug [JbpmConfiguration] for key 'jbpm.service.factory', an object of type 'org.jbpm.impl.DefaultServiceFactory' was instantiated
              2005-12-29 17:01:39,639 INFO [STDOUT] [INFO] [ID : ListenerServlet remoteCall] : [OUTPUT] <remoteCall service="CMN_ISAKISI_STATE_GETIR" sessionID="1135868463126.0"/>
              2005-12-29 17:01:39,649 INFO [STDOUT] [INFO] [ID : ServerServlet remoteCall] : [OUTPUT] <remoteCall service="CMN_ISAKISI_STATE_GETIR" sessionID="1135868463126.0"/>
              2005-12-29 17:01:43,555 INFO [STDOUT] [INFO] [ID : ServerServlet remoteCall] : [INPUT] <remoteCall service="CMN_YZSM_GIDEN_IC_YAZI_KAYDET" localIP="10.1.7.220" client="ERE" gateIP="127.0.0.1" domain="TPAO" sessionID="1135868463126.0" language="tr"/>
              2005-12-29 17:01:43,565 INFO [STDOUT] [INFO] [ID : ListenerServlet remoteCall] : [INPUT] <remoteCall service="CMN_YZSM_GIDEN_IC_YAZI_KAYDET" client="ERE" localIP="10.1.7.220" gateIP="127.0.0.1" domain="TPAO" sessionID="1135868463126.0" language="tr"/>
              2005-12-29 17:01:43,805 INFO [STDOUT] [INFO] [ID : ListenerServlet remoteCall] : [OUTPUT] <remoteCall service="CMN_YZSM_GIDEN_IC_YAZI_KAYDET" sessionID="1135868463126.0"/>
              2005-12-29 17:01:43,835 INFO [STDOUT] [INFO] [ID : ServerServlet remoteCall] : [OUTPUT] <remoteCall service="CMN_YZSM_GIDEN_IC_YAZI_KAYDET" sessionID="1135868463126.0"/>
              2005-12-29 17:01:44,326 INFO [STDOUT] [INFO] [ID : ServerServlet remoteCall] : [INPUT] <remoteCall service="CMN_ISAKISI_STATE_GETIR" localIP="10.1.7.220" client="ERE" gateIP="127.0.0.1" domain="TPAO" sessionID="1135868463126.0" language="tr"/>
              2005-12-29 17:01:44,336 INFO [STDOUT] [INFO] [ID : ListenerServlet remoteCall] : [INPUT] <remoteCall service="CMN_ISAKISI_STATE_GETIR" client="ERE" localIP="10.1.7.220" gateIP="127.0.0.1" domain="TPAO" sessionID="1135868463126.0" language="tr"/>
              2005-12-29 17:01:44,376 INFO [STDOUT] [INFO] [ID : ListenerServlet remoteCall] : [OUTPUT] <remoteCall service="CMN_ISAKISI_STATE_GETIR" sessionID="1135868463126.0"/>
              2005-12-29 17:01:44,376 INFO [STDOUT] [INFO] [ID : ServerServlet remoteCall] : [OUTPUT] <remoteCall service="CMN_ISAKISI_STATE_GETIR" sessionID="1135868463126.0"/>
              2005-12-29 17:01:45,077 INFO [STDOUT] [INFO] [ID : ServerServlet remoteCall] : [INPUT] <remoteCall service="CMN_ISAKISI_UNITEDE_USTLERI_KOMBOYA_DOLDUR" localIP="10.1.7.220" client="ERE" gateIP="127.0.0.1" domain="TPAO" sessionID="1135868463126.0" language="tr"/>
              2005-12-29 17:01:45,087 INFO [STDOUT] [INFO] [ID : ListenerServlet remoteCall] : [INPUT] <remoteCall service="CMN_ISAKISI_UNITEDE_USTLERI_KOMBOYA_DOLDUR" client="ERE" localIP="10.1.7.220" gateIP="127.0.0.1" domain="TPAO" sessionID="1135868463126.0" language="tr"/>
              2005-12-29 17:01:46,469 INFO [STDOUT] 17:01:46,469 debug [SessionImpl] running Session.finalize()
              2005-12-29 17:01:48,462 INFO [STDOUT] [INFO] [ID : ListenerServlet remoteCall] : [OUTPUT] <remoteCall service="CMN_ISAKISI_UNITEDE_USTLERI_KOMBOYA_DOLDUR" sessionID="1135868463126.0"/>
              2005-12-29 17:01:48,552 INFO [STDOUT] [INFO] [ID : ServerServlet remoteCall] : [OUTPUT] <remoteCall service="CMN_ISAKISI_UNITEDE_USTLERI_KOMBOYA_DOLDUR" sessionID="1135868463126.0"/>
              2005-12-29 17:01:51,536 INFO [STDOUT] [INFO] [ID : ServerServlet remoteCall] : [INPUT] <remoteCall service="CMN_ISAKISI_ONAYA_GONDER" localIP="10.1.7.220" client="ERE" gateIP="127.0.0.1" domain="TPAO" sessionID="1135868463126.0" language="tr"/>
              2005-12-29 17:01:51,556 INFO [STDOUT] [INFO] [ID : ListenerServlet remoteCall] : [INPUT] <remoteCall service="CMN_ISAKISI_ONAYA_GONDER" client="ERE" localIP="10.1.7.220" gateIP="127.0.0.1" domain="TPAO" sessionID="1135868463126.0" language="tr"/>
              2005-12-29 17:01:51,736 INFO [STDOUT] Parse edilen ?ema ad?: Common/Yazismalar/TPAOKKY1_CMN_Giden_Ic_Yazi_OnayaGonder
              2005-12-29 17:01:51,736 INFO [STDOUT] TEK AKI? BA?LATMA
              2005-12-29 17:01:51,736 INFO [STDOUT] BA?LATILAN AKI?IN BOMOIDsi oBag'den al?nd?: 40iotkehssgouq00
              2005-12-29 17:01:51,736 INFO [STDOUT] ATANACAK AKTOR OID: MERKEZ0000003119
              2005-12-29 17:01:51,937 INFO [STDOUT] 17:01:51,937 debug [SessionImpl] opened session
              2005-12-29 17:01:52,127 INFO [STDOUT] 17:01:52,127 debug [SessionImpl] Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects
              2005-12-29 17:01:52,127 INFO [STDOUT] 17:01:52,127 debug [SessionImpl] Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
              2005-12-29 17:01:52,137 INFO [STDOUT] 17:01:52,137 debug [QueryTranslator] HQL: select d from d in class org.jbpm.model.definition.impl.DefinitionImpl where d.name = ? and d.version = ( select max(d2.version) from d2 in class org.jbpm.model.definition.impl.DefinitionImpl where d2.name = d.name )
              2005-12-29 17:01:52,137 INFO [STDOUT] 17:01:52,137 debug [QueryTranslator] SQL: select d.id as id, d.name as name, d.description as descript3_, d.definition as definition, d.version_ as version_, d.startState as startState, d.endState as endState from JBPM_DEFINITION d where (d.name=? )and(d.version_=(select max(d2.version_) from JBPM_DEFINITION d2 where (d2.name=d.name )))
              2005-12-29 17:01:52,147 INFO [STDOUT] 17:01:52,147 debug [SQL] select d.id as id, d.name as name, d.description as descript3_, d.definition as definition, d.version_ as version_, d.startState as startState, d.endState as endState from JBPM_DEFINITION d where (d.name=? )and(d.version_=(select max(d2.version_) from JBPM_DEFINITION d2 where (d2.name=d.name )))
              2005-12-29 17:01:52,167 INFO [STDOUT] 17:01:52,167 debug [Loader] result row: 13107224
              2005-12-29 17:01:52,197 INFO [STDOUT] 17:01:52,197 debug [SessionImpl] resolving associations for [org.jbpm.model.definition.impl.DefinitionImpl#13107224]
              2005-12-29 17:01:52,217 INFO [STDOUT] 17:01:52,217 debug [SQL] select startstate0_.id as id6_, startstate0_.swimlane as swimlane6_, startstate0_.assignmentRequired as assignme7_6_, startstate0_.stateAuthentication as stateAut8_6_, startstate0_.name as name6_, startstate0_.description as descript4_6_, startstate0_.definition as definition6_, swimlane?m1_.id as id0_, swimlane?m1_.name as name0_, swimlane?m1_.description as descript3_0_, swimlane?m1_.definition as definition0_, swimlane?m1_.delegation as delegation0_, definition2_.id as id1_, definition2_.name as name1_, definition2_.description as descript3_1_, definition2_.definition as definition1_, definition2_.version_ as version_1_, definition2_.startState as startState1_, definition2_.endState as endState1_, definition3_.id as id2_, definition3_.name as name2_, definition3_.description as descript3_2_, definition3_.definition as definition2_, definition3_.version_ as version_2_, definition3_.startState as startState2_, definition3_.endState as endState2_, endstate?m4_.id as id3_, endstate?m4_.swimlane as swimlane3_, endstate?m4_.assignmentRequired as assignme7_3_, endstate?m4_.stateAuthentication as stateAut8_3_, endstate?m4_.name as name3_, endstate?m4_.description as descript4_3_, endstate?m4_.definition as definition3_, endstate?m5_.id as id4_, endstate?m5_.swimlane as swimlane4_, endstate?m5_.assignmentRequired as assignme7_4_, endstate?m5_.stateAuthentication as stateAut8_4_, endstate?m5_.name as name4_, endstate?m5_.description as descript4_4_, endstate?m5_.definition as definition4_, delegation6_.id as id5_, delegation6_.definition as definition5_, delegation6_.className as className5_, delegation6_.configuration as configur4_5_ from JBPM_NODE startstate0_, JBPM_SWIMLANE swimlane?m1_, JBPM_DEFINITION definition2_, JBPM_DEFINITION definition3_, JBPM_NODE endstate?m4_, JBPM_NODE endstate?m5_, JBPM_DELEGATION delegation6_ where startstate0_.id=? and startstate0_.swimlane *= swimlane?m1_.id and swimlane?m1_.definition *= definition2_.id and definition2_.definition *= definition3_.id and definition3_.endState *= endstate?m4_.id and definition2_.endState *= endstate?m5_.id and swimlane?m1_.delegation *= delegation6_.id
              2005-12-29 17:01:52,247 INFO [STDOUT] 17:01:52,247 debug [Loader] result row: 13107225, 13107224, null, null, 13107238, null, 13107241
              2005-12-29 17:01:52,247 INFO [STDOUT] 17:01:52,247 debug [SessionImpl] resolving associations for [org.jbpm.model.definition.impl.SwimlaneImpl#13107225]
              2005-12-29 17:01:52,247 INFO [STDOUT] 17:01:52,247 debug [SessionImpl] done materializing entity [org.jbpm.model.definition.impl.SwimlaneImpl#13107225]
              2005-12-29 17:01:52,247 INFO [STDOUT] 17:01:52,247 debug [SessionImpl] resolving associations for [org.jbpm.model.definition.impl.EndStateImpl#13107238]
              2005-12-29 17:01:52,247 INFO [STDOUT] 17:01:52,247 debug [SessionImpl] done materializing entity [org.jbpm.model.definition.impl.EndStateImpl#13107238]
              2005-12-29 17:01:52,247 INFO [STDOUT] 17:01:52,247 debug [SessionImpl] resolving associations for [org.jbpm.model.definition.impl.StartStateImpl#13107241]
              2005-12-29 17:01:52,247 INFO [STDOUT] 17:01:52,247 debug [SessionImpl] done materializing entity [org.jbpm.model.definition.impl.StartStateImpl#13107241]
              2005-12-29 17:01:52,247 INFO [STDOUT] 17:01:52,247 debug [SessionImpl] done materializing entity [org.jbpm.model.definition.impl.DefinitionImpl#13107224]
              2005-12-29 17:01:52,247 INFO [STDOUT] 17:01:52,247 debug [SessionImpl] initializing non-lazy collections
              2005-12-29 17:01:52,247 INFO [STDOUT] 17:01:52,247 info [ExecutionServiceImpl] starting a process instance for '4zg6y0egyr3w3m08' of definition '13107224'...
              2005-12-29 17:01:52,247 INFO [STDOUT] 17:01:52,247 debug [SequenceBlockIdGenerator] getting a new block of ids: 0, -1
              2005-12-29 17:01:52,247 INFO [STDOUT] 17:01:52,247 debug [SessionImpl] opened session
              2005-12-29 17:01:52,247 INFO [STDOUT] 17:01:52,247 debug [SequenceBlockIdGenerator] beginning a jbpm id-sequence transaction
              2005-12-29 17:01:52,267 INFO [STDOUT] 17:01:52,267 debug [JTATransaction] Looking for UserTransaction under: UserTransaction
              2005-12-29 17:01:52,267 INFO [STDOUT] 17:01:52,267 debug [JTATransaction] Obtained UserTransaction
              2005-12-29 17:01:52,267 INFO [STDOUT] 17:01:52,267 debug [SequenceBlockIdGenerator] check the sequence block in the db for nodeId: 3
              2005-12-29 17:01:52,267 INFO [STDOUT] 17:01:52,267 debug [SQL] select sequencebl0_.id as id0_, sequencebl0_.nextId as nextId0_ from JBPM_SEQUENCEBLOCK sequencebl0_ where sequencebl0_.id=?
              2005-12-29 17:01:52,287 INFO [STDOUT] 17:01:52,287 debug [Loader] result row: 3
              2005-12-29 17:01:52,287 INFO [STDOUT] 17:01:52,287 debug [SessionImpl] resolving associations for [org.jbpm.persistence.hibernate.SequenceBlock#3]
              2005-12-29 17:01:52,287 INFO [STDOUT] 17:01:52,287 debug [SessionImpl] done materializing entity [org.jbpm.persistence.hibernate.SequenceBlock#3]
              2005-12-29 17:01:52,287 INFO [STDOUT] 17:01:52,287 debug [SessionImpl] initializing non-lazy collections
              2005-12-29 17:01:52,287 INFO [STDOUT] 17:01:52,287 debug [SequenceBlockIdGenerator] fetched sequence block from db: SequenceBlock[3|6553601]
              2005-12-29 17:01:52,287 INFO [STDOUT] 17:01:52,287 debug [SequenceBlockIdGenerator] first id of overall block is: 6553601
              2005-12-29 17:01:52,307 INFO [STDOUT] 17:01:52,307 debug [SessionImpl] Flushed: 0 insertions, 1 updates, 0 deletions to 1 objects
              2005-12-29 17:01:52,307 INFO [STDOUT] 17:01:52,307 debug [SessionImpl] Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
              2005-12-29 17:01:52,307 INFO [STDOUT] 17:01:52,307 debug [Printer] listing entities:
              2005-12-29 17:01:52,307 INFO [STDOUT] 17:01:52,307 debug [Printer] org.jbpm.persistence.hibernate.SequenceBlock{nextId=13107201, id=3}
              2005-12-29 17:01:52,307 INFO [STDOUT] 17:01:52,307 debug [SQL] update JBPM_SEQUENCEBLOCK set nextId=? where id=?
              2005-12-29 17:01:52,317 INFO [STDOUT] 17:01:52,317 debug [SequenceBlockIdGenerator] committing a jbpm id-sequence transaction
              2005-12-29 17:01:52,317 INFO [STDOUT] 17:01:52,317 debug [JTATransaction] commit
              2005-12-29 17:01:52,317 INFO [STDOUT] 17:01:52,317 debug [SessionImpl] Flushed: 0 insertions, 0 updates, 0 deletions to 1 objects
              2005-12-29 17:01:52,317 INFO [STDOUT] 17:01:52,317 debug [SessionImpl] Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
              2005-12-29 17:01:52,317 INFO [STDOUT] 17:01:52,317 debug [Printer] listing entities:
              2005-12-29 17:01:52,317 INFO [STDOUT] 17:01:52,317 debug [Printer] org.jbpm.persistence.hibernate.SequenceBlock{nextId=13107201, id=3}
              2005-12-29 17:01:52,317 INFO [STDOUT] 17:01:52,317 debug [SequenceBlockIdGenerator] updated sequence block SequenceBlock[3|13107201] was commited
              2005-12-29 17:01:52,317 INFO [STDOUT] 17:01:52,317 debug [SequenceBlockIdGenerator] got a new block of ids for this node: 6553901, 6554000
              2005-12-29 17:01:52,317 INFO [STDOUT] 17:01:52,317 debug [SessionImpl] disconnecting session
              2005-12-29 17:01:52,327 INFO [STDOUT] 17:01:52,327 debug [JDBCExceptionReporter] SQL Warning
              2005-12-29 17:01:52,327 INFO [STDOUT] caused by exception : java.sql.SQLWarning
              2005-12-29 17:01:52,327 INFO [STDOUT] java.sql.SQLWarning: 010TP: The connection's initial character set, null, could not be converted by the server. The server's proposed character set, 8859_9, will be used, with conversions performed by jConnect.
              2005-12-29 17:01:52,327 INFO [STDOUT] at com.sybase.jdbc2.jdbc.SybConnection.convertToWarnings(SybConnection.java:1308)
              2005-12-29 17:01:52,327 INFO [STDOUT] at com.sybase.jdbc2.jdbc.SybConnection.chainWarnings(SybConnection.java:1324)
              2005-12-29 17:01:52,327 INFO [STDOUT] at com.sybase.jdbc2.tds.Tds.processLoginAckToken(Tds.java:2996)
              2005-12-29 17:01:52,327 INFO [STDOUT] at com.sybase.jdbc2.tds.Tds.doLogin(Tds.java:422)
              2005-12-29 17:01:52,327 INFO [STDOUT] at com.sybase.jdbc2.tds.Tds.login(Tds.java:350)
              2005-12-29 17:01:52,327 INFO [STDOUT] at com.sybase.jdbc2.jdbc.SybConnection.tryLogin(SybConnection.java:213)
              2005-12-29 17:01:52,327 INFO [STDOUT] at com.sybase.jdbc2.jdbc.SybConnection.regularConnect(SybConnection.java:193)
              2005-12-29 17:01:52,327 INFO [STDOUT] at com.sybase.jdbc2.jdbc.SybConnection.<init>(SybConnection.java:172)
              2005-12-29 17:01:52,327 INFO [STDOUT] at com.sybase.jdbc2.jdbc.SybConnection.<init>(SybConnection.java:124)
              2005-12-29 17:01:52,327 INFO [STDOUT] at com.sybase.jdbc2.jdbc.SybDriver.connect(SybDriver.java:181)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.resource.adapter.jdbc.local.LocalManagedConnectionFactory.createManagedConnection(LocalManagedConnectionFactory.java:151)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.resource.connectionmanager.InternalManagedConnectionPool.createConnectionEventListener(InternalManagedConnectionPool.java:508)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.resource.connectionmanager.InternalManagedConnectionPool.getConnection(InternalManagedConnectionPool.java:207)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.resource.connectionmanager.JBossManagedConnectionPool$BasePool.getConnection(JBossManagedConnectionPool.java:534)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.resource.connectionmanager.BaseConnectionManager2.getManagedConnection(BaseConnectionManager2.java:396)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.resource.connectionmanager.TxConnectionManager.getManagedConnection(TxConnectionManager.java:299)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.resource.connectionmanager.BaseConnectionManager2.allocateConnection(BaseConnectionManager2.java:448)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.resource.connectionmanager.BaseConnectionManager2$ConnectionManagerProxy.allocateConnection(BaseConnectionManager2.java:838)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.resource.adapter.jdbc.WrapperDataSource.getConnection(WrapperDataSource.java:102)
              2005-12-29 17:01:52,327 INFO [STDOUT] at tr.com.cs.aurora.auroracore.utility.CSJDBCUtility.getDataSource(Unknown Source)
              2005-12-29 17:01:52,327 INFO [STDOUT] at tr.com.cs.aurora.auroracore.utility.CSJDBCUtility.getConnection(Unknown Source)
              2005-12-29 17:01:52,327 INFO [STDOUT] at tr.com.cs.aurora.auroracore.utility.CSSession.getConnectionFrom(Unknown Source)
              2005-12-29 17:01:52,327 INFO [STDOUT] at tr.com.cs.aurora.auroracore.utility.CSDefaultCacheLoader.loadData(Unknown Source)
              2005-12-29 17:01:52,327 INFO [STDOUT] at tr.com.cs.aurora.auroracore.utility.CSDefaultCacheLoader.loadReferenceData(Unknown Source)
              2005-12-29 17:01:52,327 INFO [STDOUT] at tr.com.cs.aurora.auroracore.utility.CSCacheLoader.loadReferenceData(Unknown Source)
              2005-12-29 17:01:52,327 INFO [STDOUT] at tr.com.cs.aurora.auroracore.utility.CSReferenceDataCache.refresh(Unknown Source)
              2005-12-29 17:01:52,327 INFO [STDOUT] at tr.com.cs.aurora.auroracore.utility.CSCacheFactory.loadCaches(Unknown Source)
              2005-12-29 17:01:52,327 INFO [STDOUT] at tr.com.cs.aurora.auroracore.utility.CSCacheFactory.initialize(Unknown Source)
              2005-12-29 17:01:52,327 INFO [STDOUT] at tr.com.cs.aurora.auroracore.listener.ejb.ListenerBean.initialize(Unknown Source)
              2005-12-29 17:01:52,327 INFO [STDOUT] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
              2005-12-29 17:01:52,327 INFO [STDOUT] at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
              2005-12-29 17:01:52,327 INFO [STDOUT] at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
              2005-12-29 17:01:52,327 INFO [STDOUT] at java.lang.reflect.Method.invoke(Unknown Source)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.invocation.Invocation.performCall(Invocation.java:345)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.ejb.StatelessSessionContainer$ContainerInterceptor.invoke(StatelessSessionContainer.java:214)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.resource.connectionmanager.CachedConnectionInterceptor.invoke(CachedConnectionInterceptor.java:185)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.ejb.plugins.StatelessSessionInstanceInterceptor.invoke(StatelessSessionInstanceInterceptor.java:113)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.webservice.server.ServiceEndpointInterceptor.invoke(ServiceEndpointInterceptor.java:51)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.ejb.plugins.CallValidationInterceptor.invoke(CallValidationInterceptor.java:48)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.ejb.plugins.AbstractTxInterceptor.invokeNext(AbstractTxInterceptor.java:105)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.ejb.plugins.TxInterceptorCMT.runWithTransactions(TxInterceptorCMT.java:313)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.ejb.plugins.TxInterceptorCMT.invoke(TxInterceptorCMT.java:146)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.ejb.plugins.SecurityInterceptor.invoke(SecurityInterceptor.java:123)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.ejb.plugins.LogInterceptor.invoke(LogInterceptor.java:192)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.ejb.plugins.ProxyFactoryFinderInterceptor.invoke(ProxyFactoryFinderInterceptor.java:122)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.ejb.SessionContainer.internalInvoke(SessionContainer.java:624)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.ejb.Container.invoke(Container.java:870)
              2005-12-29 17:01:52,327 INFO [STDOUT] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
              2005-12-29 17:01:52,327 INFO [STDOUT] at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
              2005-12-29 17:01:52,327 INFO [STDOUT] at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
              2005-12-29 17:01:52,327 INFO [STDOUT] at java.lang.reflect.Method.invoke(Unknown Source)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:144)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.mx.server.Invocation.dispatch(Invocation.java:80)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.mx.server.Invocation.invoke(Invocation.java:72)
              2005-12-29 17:01:52,327 INFO [STDOUT] at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:249)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:642)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.invocation.local.LocalInvoker$MBeanServerAction.invoke(LocalInvoker.java:155)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.invocation.local.LocalInvoker.invoke(LocalInvoker.java:104)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.invocation.InvokerInterceptor.invokeLocal(InvokerInterceptor.java:115)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.invocation.InvokerInterceptor.invoke(InvokerInterceptor.java:101)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.proxy.TransactionInterceptor.invoke(TransactionInterceptor.java:46)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.proxy.SecurityInterceptor.invoke(SecurityInterceptor.java:55)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.proxy.ejb.StatelessSessionInterceptor.invoke(StatelessSessionInterceptor.java:97)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.proxy.ClientContainer.invoke(ClientContainer.java:91)
              2005-12-29 17:01:52,337 INFO [STDOUT] at $Proxy55.initialize(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at tr.com.cs.aurora.auroracore.listener.ejb.ListenerClient.initialize(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at tr.com.cs.aurora.auroracore.listener.servlet.ListenerServlet.init(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at javax.servlet.GenericServlet.init(GenericServlet.java:211)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1029)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:862)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:4013)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.apache.catalina.core.StandardContext.start(StandardContext.java:4357)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:823)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:807)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:595)
              2005-12-29 17:01:52,337 INFO [STDOUT] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
              2005-12-29 17:01:52,337 INFO [STDOUT] at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at java.lang.reflect.Method.invoke(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.apache.commons.modeler.BaseModelMBean.invoke(BaseModelMBean.java:503)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.mx.server.RawDynamicInvoker.invoke(RawDynamicInvoker.java:150)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:642)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.apache.catalina.core.StandardContext.init(StandardContext.java:5441)
              2005-12-29 17:01:52,337 INFO [STDOUT] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
              2005-12-29 17:01:52,337 INFO [STDOUT] at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at java.lang.reflect.Method.invoke(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.apache.commons.modeler.BaseModelMBean.invoke(BaseModelMBean.java:503)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.mx.server.RawDynamicInvoker.invoke(RawDynamicInvoker.java:150)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:642)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.web.tomcat.tc5.TomcatDeployer.performDeployInternal(TomcatDeployer.java:332)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.web.tomcat.tc5.TomcatDeployer.performDeploy(TomcatDeployer.java:85)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.web.AbstractWebDeployer.start(AbstractWebDeployer.java:374)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.web.WebModule.startModule(WebModule.java:62)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.web.WebModule.startService(WebModule.java:40)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.system.ServiceMBeanSupport.jbossInternalStart(ServiceMBeanSupport.java:272)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.system.ServiceMBeanSupport.jbossInternalLifecycle(ServiceMBeanSupport.java:222)
              2005-12-29 17:01:52,337 INFO [STDOUT] at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at java.lang.reflect.Method.invoke(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:144)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.mx.server.Invocation.dispatch(Invocation.java:80)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.mx.server.Invocation.invoke(Invocation.java:72)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:249)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:642)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.system.ServiceController$ServiceProxy.invoke(ServiceController.java:891)
              2005-12-29 17:01:52,337 INFO [STDOUT] at $Proxy0.start(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.system.ServiceController.start(ServiceController.java:416)
              2005-12-29 17:01:52,337 INFO [STDOUT] at sun.reflect.GeneratedMethodAccessor10.invoke(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at java.lang.reflect.Method.invoke(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:144)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.mx.server.Invocation.dispatch(Invocation.java:80)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.mx.server.Invocation.invoke(Invocation.java:72)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:249)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:642)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:177)
              2005-12-29 17:01:52,337 INFO [STDOUT] at $Proxy28.start(Unknown Source)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.web.AbstractWebContainer.start(AbstractWebContainer.java:360)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.deployment.MainDeployer.start(MainDeployer.java:964)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.deployment.MainDeployer.start(MainDeployer.java:956)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:775)
              2005-12-29 17:01:52,337 INFO [STDOUT] at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:738)
              2005-12-29 17:01:52,337 INFO [STDOUT] at sun.reflect.GeneratedMethodAccessor48.invoke(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] at java.lang.reflect.Method.invoke(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:144)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.Invocation.dispatch(Invocation.java:80)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.interceptor.AbstractInterceptor.invoke(AbstractInterceptor.java:122)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.Invocation.invoke(Invocation.java:74)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.interceptor.ModelMBeanOperationInterceptor.invoke(ModelMBeanOperationInterceptor.java:131)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.Invocation.invoke(Invocation.java:74)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:249)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:642)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:177)
              2005-12-29 17:01:52,347 INFO [STDOUT] at $Proxy8.deploy(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.deployment.scanner.URLDeploymentScanner.deploy(URLDeploymentScanner.java:305)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.deployment.scanner.URLDeploymentScanner.scan(URLDeploymentScanner.java:481)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.deployment.scanner.AbstractDeploymentScanner$ScannerThread.doScan(AbstractDeploymentScanner.java:204)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.deployment.scanner.AbstractDeploymentScanner.startService(AbstractDeploymentScanner.java:277)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.system.ServiceMBeanSupport.jbossInternalStart(ServiceMBeanSupport.java:272)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.system.ServiceMBeanSupport.jbossInternalLifecycle(ServiceMBeanSupport.java:222)
              2005-12-29 17:01:52,347 INFO [STDOUT] at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] at java.lang.reflect.Method.invoke(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:144)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.Invocation.dispatch(Invocation.java:80)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.Invocation.invoke(Invocation.java:72)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:249)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:642)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.system.ServiceController$ServiceProxy.invoke(ServiceController.java:891)
              2005-12-29 17:01:52,347 INFO [STDOUT] at $Proxy0.start(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.system.ServiceController.start(ServiceController.java:416)
              2005-12-29 17:01:52,347 INFO [STDOUT] at sun.reflect.GeneratedMethodAccessor10.invoke(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] at java.lang.reflect.Method.invoke(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:144)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.Invocation.dispatch(Invocation.java:80)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.Invocation.invoke(Invocation.java:72)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:249)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:642)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:177)
              2005-12-29 17:01:52,347 INFO [STDOUT] at $Proxy4.start(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.deployment.SARDeployer.start(SARDeployer.java:261)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.deployment.MainDeployer.start(MainDeployer.java:964)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:775)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:738)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:722)
              2005-12-29 17:01:52,347 INFO [STDOUT] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
              2005-12-29 17:01:52,347 INFO [STDOUT] at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] at java.lang.reflect.Method.invoke(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:144)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.Invocation.dispatch(Invocation.java:80)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.interceptor.AbstractInterceptor.invoke(AbstractInterceptor.java:122)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.Invocation.invoke(Invocation.java:74)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.interceptor.ModelMBeanOperationInterceptor.invoke(ModelMBeanOperationInterceptor.java:131)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.Invocation.invoke(Invocation.java:74)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:249)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:642)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:177)
              2005-12-29 17:01:52,347 INFO [STDOUT] at $Proxy5.deploy(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.system.server.ServerImpl.doStart(ServerImpl.java:413)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.system.server.ServerImpl.start(ServerImpl.java:310)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.Main.boot(Main.java:162)
              2005-12-29 17:01:52,347 INFO [STDOUT] at org.jboss.Main$1.run(Main.java:423)
              2005-12-29 17:01:52,347 INFO [STDOUT] at java.lang.Thread.run(Unknown Source)
              2005-12-29 17:01:52,347 INFO [STDOUT] 17:01:52,347 warn [JDBCExceptionReporter] SQL Warning: 0, SQLState: 010TP
              2005-12-29 17:01:52,347 INFO [STDOUT] 17:01:52,347 warn [JDBCExceptionReporter] 010TP: The connection's initial character set, null, could not be converted by the server. The server's proposed character set, 8859_9, will be used, with conversions performed by jConnect.
              2005-12-29 17:01:52,347 INFO [STDOUT] 17:01:52,347 debug [SessionImpl] generated identifier: 6553901
              2005-12-29 17:01:52,377 INFO [STDOUT] 17:01:52,377 debug [SessionImpl] generated identifier: 6553902
              2005-12-29 17:01:52,407 INFO [STDOUT] 17:01:52,407 debug [SessionImpl] generated identifier: 6553903
              2005-12-29 17:01:52,497 INFO [STDOUT] 17:01:52,497 debug [SQL] select variables0_.definition as definition__, variables0_.id as id__, variables0_.name as name__, variables0_.id as id9_, variables0_.name as name9_, variables0_.definition as definition9_, variables0_.type_ as type_9_, type?mpl1_.id as id0_, type?mpl1_.index_ as index_0_, type?mpl1_.description as descript3_0_, type?mpl1_.javaType as javaType0_, type?mpl1_.definition as definition0_, type?mpl1_.delegation as delegation0_, definition2_.id as id1_, definition2_.name as name1_, definition2_.description as descript3_1_, definition2_.definition as definition1_, definition2_.version_ as version_1_, definition2_.startState as startState1_, definition2_.endState as endState1_, definition3_.id as id2_, definition3_.name as name2_, definition3_.description as descript3_2_, definition3_.definition as definition2_, definition3_.version_ as version_2_, definition3_.startState as startState2_, definition3_.endState as endState2_, startstate4_.id as id3_, startstate4_.swimlane as swimlane3_, startstate4_.assignmentRequired as assignme7_3_, startstate4_.stateAuthentication as stateAut8_3_, startstate4_.name as name3_, startstate4_.description as descript4_3_, startstate4_.definition as definition3_, swimlane?m5_.id as id4_, swimlane?m5_.name as name4_, swimlane?m5_.description as descript3_4_, swimlane?m5_.definition as definition4_, swimlane?m5_.delegation as delegation4_, delegation6_.id as id5_, delegation6_.definition as definition5_, delegation6_.className as className5_, delegation6_.configuration as configur4_5_, endstate?m7_.id as id6_, endstate?m7_.swimlane as swimlane6_, endstate?m7_.assignmentRequired as assignme7_6_, endstate?m7_.stateAuthentication as stateAut8_6_, endstate?m7_.name as name6_, endstate?m7_.description as descript4_6_, endstate?m7_.definition as definition6_, endstate?m8_.id as id7_, endstate?m8_.swimlane as swimlane7_, endstate?m8_.assignmentRequired as assignme7_7_, endstate?m8_.stateAuthentication as stateAut8_7_, endstate?m8_.name as name7_, endstate?m8_.description as descript4_7_, endstate?m8_.definition as definition7_, delegation9_.id as id8_, delegation9_.definition as definition8_, delegation9_.className as className8_, delegation9_.configuration as configur4_8_ from JBPM_VARIABLE variables0_, JBPM_TYPE type?mpl1_, JBPM_DEFINITION definition2_, JBPM_DEFINITION definition3_, JBPM_NODE startstate4_, JBPM_SWIMLANE swimlane?m5_, JBPM_DELEGATION delegation6_, JBPM_NODE endstate?m7_, JBPM_NODE endstate?m8_, JBPM_DELEGATION delegation9_ where variables0_.definition=? and variables0_.type_ *= type?mpl1_.id and type?mpl1_.definition *= definition2_.id and definition2_.definition *= definition3_.id and definition3_.startState *= startstate4_.id and startstate4_.swimlane *= swimlane?m5_.id and swimlane?m5_.delegation *= delegation6_.id and definition3_.endState *= endstate?m7_.id and definition2_.endState *= endstate?m8_.id and type?mpl1_.delegation *= delegation9_.id
              2005-12-29 17:01:52,537 INFO [STDOUT] 17:01:52,537 debug [Loader] result set contains (possibly empty) collection: [org.jbpm.model.definition.impl.DefinitionImpl.variables#13107224]
              2005-12-29 17:01:52,547 INFO [STDOUT] 17:01:52,547 debug [SessionImpl] 1 collections were found in result set
              2005-12-29 17:01:52,547 INFO [STDOUT] 17:01:52,547 debug [SessionImpl] collection fully initialized: [org.jbpm.model.definition.impl.DefinitionImpl.variables#13107224]
              2005-12-29 17:01:52,547 INFO [STDOUT] 17:01:52,547 debug [SessionImpl] 1 collections initialized
              2005-12-29 17:01:52,547 INFO [STDOUT] 17:01:52,547 debug [SessionImpl] initializing non-lazy collections
              2005-12-29 17:01:52,547 INFO [STDOUT] 17:01:52,547 debug [SQL] select types0_.definition as definition__, types0_.id as id__, types0_.index_ as index___, types0_.id as id7_, types0_.index_ as index_7_, types0_.description as descript3_7_, types0_.javaType as javaType7_, types0_.definition as definition7_, types0_.delegation as delegation7_, delegation1_.id as id0_, delegation1_.definition as definition0_, delegation1_.className as className0_, delegation1_.configuration as configur4_0_, definition2_.id as id1_, definition2_.name as name1_, definition2_.description as descript3_1_, definition2_.definition as definition1_, definition2_.version_ as version_1_, definition2_.startState as startState1_, definition2_.endState as endState1_, definition3_.id as id2_, definition3_.name as name2_, definition3_.description as descript3_2_, definition3_.definition as definition2_, definition3_.version_ as version_2_, definition3_.startState as startState2_, definition3_.endState as endState2_, startstate4_.id as id3_, startstate4_.swimlane as swimlane3_, startstate4_.assignmentRequired as assignme7_3_, startstate4_.stateAuthentication as stateAut8_3_, startstate4_.name as name3_, startstate4_.description as descript4_3_, startstate4_.definition as definition3_, swimlane?m5_.id as id4_, swimlane?m5_.name as name4_, swimlane?m5_.description as descript3_4_, swimlane?m5_.definition as definition4_, swimlane?m5_.delegation as delegation4_, endstate?m6_.id as id5_, endstate?m6_.swimlane as swimlane5_, endstate?m6_.assignmentRequired as assignme7_5_, endstate?m6_.stateAuthentication as stateAut8_5_, endstate?m6_.name as name5_, endstate?m6_.description as descript4_5_, endstate?m6_.definition as definition5_, swimlane?m7_.id as id6_, swimlane?m7_.name as name6_, swimlane?m7_.description as descript3_6_, swimlane?m7_.definition as definition6_, swimlane?m7_.delegation as delegation6_ from JBPM_TYPE types0_, JBPM_DELEGATION delegation1_, JBPM_DEFINITION definition2_, JBPM_DEFINITION definition3_, JBPM_NODE startstate4_, JBPM_SWIMLANE swimlane?m5_, JBPM_NODE endstate?m6_, JBPM_SWIMLANE swimlane?m7_ where types0_.definition=? and types0_.delegation *= delegation1_.id and delegation1_.definition *= definition2_.id and definition2_.definition *= definition3_.id and definition3_.startState *= startstate4_.id and startstate4_.swimlane *= swimlane?m5_.id and definition3_.endState *= endstate?m6_.id and endstate?m6_.swimlane *= swimlane?m7_.id
              2005-12-29 17:01:52,567 INFO [STDOUT] 17:01:52,567 debug [Loader] result set contains (possibly empty) collection: [org.jbpm.model.definition.impl.DefinitionImpl.types#13107224]
              2005-12-29 17:01:52,567 INFO [STDOUT] 17:01:52,567 debug [Loader] result row: 13107229, 13107224, null, null, null, null, null, 13107228
              2005-12-29 17:01:52,567 INFO [STDOUT] 17:01:52,567 debug [Loader] found row of collection: [org.jbpm.model.definition.impl.DefinitionImpl.types#13107224]
              2005-12-29 17:01:52,578 INFO [STDOUT] 17:01:52,578 debug [SessionImpl] resolving associations for [org.jbpm.model.definition.impl.DelegationImpl#13107229]
              2005-12-29 17:01:52,578 INFO [STDOUT] 17:01:52,578 debug [SessionImpl] done materializing entity [org.jbpm.model.definition.impl.DelegationImpl#13107229]
              2005-12-29 17:01:52,578 INFO [STDOUT] 17:01:52,578 debug [SessionImpl] resolving associations for [org.jbpm.model.definition.impl.TypeImpl#13107228]
              2005-12-29 17:01:52,578 INFO [STDOUT] 17:01:52,578 debug [SessionImpl] done materializing entity [org.jbpm.model.definition.impl.TypeImpl#13107228]
              2005-12-29 17:01:52,578 INFO [STDOUT] 17:01:52,578 debug [SessionImpl] 1 collections were found in result set
              2005-12-29 17:01:52,578 INFO [STDOUT] 17:01:52,578 debug [SessionImpl] collection fully initialized: [org.jbpm.model.definition.impl.DefinitionImpl.types#13107224]
              2005-12-29 17:01:52,578 INFO [STDOUT] 17:01:52,578 debug [SessionImpl] 1 collections initialized
              2005-12-29 17:01:52,578 INFO [STDOUT] 17:01:52,578 debug [SessionImpl] initializing non-lazy collections
              2005-12-29 17:01:52,578 INFO [STDOUT] 17:01:52,578 debug [VariableInstanceImpl] setting variable 'Giden iç yaz?y? olu?turan ki?i' to value '4zg6y0egyr3w3m08' which was serialized as '4zg6y0egyr3w3m08'
              2005-12-29 17:01:52,578 INFO [STDOUT] 17:01:52,578 debug [VariableInstanceImpl] setting variable 'BOM_OID' to value '40iotkehssgouq00' which was serialized as '40iotkehssgouq00'
              2005-12-29 17:01:52,578 INFO [STDOUT] 17:01:52,578 debug [VariableInstanceImpl] setting variable 'GONDERILEN_KISI_OID' to value 'MERKEZ0000003119' which was serialized as 'MERKEZ0000003119'
              2005-12-29 17:01:52,578 INFO [STDOUT] 17:01:52,578 debug [VariableInstanceImpl] setting variable 'BASLATANIN_UNITE_KODU' to value '01.01.005' which was serialized as '01.01.005'
              2005-12-29 17:01:52,578 INFO [STDOUT] 17:01:52,578 debug [VariableInstanceImpl] setting variable 'DAGITIM_TURU0' to value '1' which was serialized as '1'
              2005-12-29 17:01:52,578 INFO [STDOUT] 17:01:52,578 debug [VariableInstanceImpl] setting variable 'UNITE_SAYISI' to value '1' which was serialized as '1'
              2005-12-29 17:01:52,578 INFO [STDOUT] 17:01:52,578 debug [VariableInstanceImpl] setting variable 'UNITE0' to value '01.01.001' which was serialized as '01.01.001'
              2005-12-29 17:01:52,578 INFO [STDOUT] 17:01:52,578 debug [SQL] select actions0_.element as element__, actions0_.id as id__, actions0_.index_ as index___, actions0_.id as id7_, actions0_.element_type as element_2_7_, actions0_.element as element7_, actions0_.index_ as index_7_, actions0_.eventType as eventType7_, actions0_.actionDelegation as actionDe6_7_, delegation1_.id as id0_, delegation1_.definition as definition0_, delegation1_.className as className0_, delegation1_.configuration as configur4_0_, definition2_.id as id1_, definition2_.name as name1_, definition2_.description as descript3_1_, definition2_.definition as definition1_, definition2_.version_ as version_1_, definition2_.startState as startState1_, definition2_.endState as endState1_, definition3_.id as id2_, definition3_.name as name2_, definition3_.description as descript3_2_, definition3_.definition as definition2_, definition3_.version_ as version_2_, definition3_.startState as startState2_, definition3_.endState as endState2_, startstate4_.id as id3_, startstate4_.swimlane as swimlane3_, startstate4_.assignmentRequired as assignme7_3_, startstate4_.stateAuthentication as stateAut8_3_, startstate4_.name as name3_, startstate4_.description as descript4_3_, startstate4_.definition as definition3_, swimlane?m5_.id as id4_, swimlane?m5_.name as name4_, swimlane?m5_.description as descript3_4_, swimlane?m5_.definition as definition4_, swimlane?m5_.delegation as delegation4_, endstate?m6_.id as id5_, endstate?m6_.swimlane as swimlane5_, endstate?m6_.assignmentRequired as assignme7_5_, endstate?m6_.stateAuthentication as stateAut8_5_, endstate?m6_.name as name5_, endstate?m6_.description as descript4_5_, endstate?m6_.definition as definition5_, swimlane?m7_.id as id6_, swimlane?m7_.name as name6_, swimlane?m7_.description as descript3_6_, swimlane?m7_.definition as definition6_, swimlane?m7_.delegation as delegation6_ from JBPM_ACTION actions0_, JBPM_DELEGATION delegation1_, JBPM_DEFINITION definition2_, JBPM_DEFINITION definition3_, JBPM_NODE startstate4_, JBPM_SWIMLANE swimlane?m5_, JBPM_NODE endstate?m6_, JBPM_SWIMLANE swimlane?m7_ where actions0_.element=? and actions0_.actionDelegation *= delegation1_.id and delegation1_.definition *= definition2_.id and definition2_.definition *= definition3_.id and definition3_.startState *= startstate4_.id and startstate4_.swimlane *= swimlane?m5_.id and definition3_.endState *= endstate?m6_.id and endstate?m6_.swimlane *= swimlane?m7_.id
              2005-12-29 17:01:52,588 INFO [STDOUT] 17:01:52,588 debug [Loader] result set contains (possibly empty) collection: [org.jbpm.model.definition.impl.DefinitionImpl.actions#13107224]
              2005-12-29 17:01:52,588 INFO [STDOUT] 17:01:52,588 debug [SessionImpl] 1 collections were found in result set
              2005-12-29 17:01:52,588 INFO [STDOUT] 17:01:52,588 debug [SessionImpl] collection fully initialized: [org.jbpm.model.definition.impl.DefinitionImpl.actions#13107224]
              2005-12-29 17:01:52,588 INFO [STDOUT] 17:01:52,588 debug [SessionImpl] 1 collections initialized
              2005-12-29 17:01:52,588 INFO [STDOUT] 17:01:52,588 debug [SessionImpl] initializing non-lazy collections
              2005-12-29 17:01:52,588 INFO [STDOUT] 17:01:52,588 debug [SQL] select leavingtra0_.from_ as from___, leavingtra0_.id as id__, leavingtra0_.id as id16_, leavingtra0_.name as name16_, leavingtra0_.description as descript3_16_, leavingtra0_.definition as definition16_, leavingtra0_.from_ as from_16_, leavingtra0_.to_ as to_16_, definition1_.id as id0_, definition1_.name as name0_, definition1_.description as descript3_0_, definition1_.definition as definition0_, definition1_.version_ as version_0_, definition1_.startState as startState0_, definition1_.endState as endState0_, definition2_.id as id1_, definition2_.name as name1_, definition2_.description as descript3_1_, definition2_.definition as definition1_, definition2_.version_ as version_1_, definition2_.startState as startState1_, definition2_.endState as endState1_, startstate3_.id as id2_, startstate3_.swimlane as swimlane2_, startstate3_.assignmentRequired as assignme7_2_, startstate3_.stateAuthentication as stateAut8_2_, startstate3_.name as name2_, startstate3_.description as descript4_2_, startstate3_.definition as definition2_, swimlane?m4_.id as id3_, swimlane?m4_.name as name3_, swimlane?m4_.description as descript3_3_, swimlane?m4_.definition as definition3_, swimlane?m4_.delegation as delegation3_, delegation5_.id as id4_, delegation5_.definition as definition4_, delegation5_.className as className4_, delegation5_.configuration as configur4_4_, endstate?m6_.id as id5_, endstate?m6_.swimlane as swimlane5_, endstate?m6_.assignmentRequired as assignme7_5_, endstate?m6_.stateAuthentication as stateAut8_5_, endstate?m6_.name as name5_, endstate?m6_.description as descript4_5_, endstate?m6_.definition as definition5_, endstate?m7_.id as id6_, endstate?m7_.swimlane as swimlane6_, endstate?m7_.assignmentRequired as assignme7_6_, endstate?m7_.stateAuthentication as stateAut8_6_, endstate?m7_.name as name6_, endstate?m7_.description as descript4_6_, endstate?m7_.definition as definition6_, node?mpl8_.id as id7_, node?mpl8_.class as class7_, node?mpl8_.name as name7_, node?mpl8_.description as descript4_7_, node?mpl8_.definition as definition7_, node?mpl8_.swimlane as swimlane7_, node?mpl8_.assignmentRequired as assignme7_7_, node?mpl8_.stateAuthentication as stateAut8_7_, node?mpl8_.delegation as delegation7_, node?mpl8_.correspondingJoin as corresp10_7_, node?mpl8_.correspondingFork as corresp11_7_, delegation9_.id as id8_, delegation9_.definition as definition8_, delegation9_.className as className8_, delegation9_.configuration as configur4_8_, delegation10_.id as id9_, delegation10_.definition as definition9_, delegation10_.className as className9_, delegation10_.configuration as configur4_9_, delegation11_.id as id10_, delegation11_.definition as definition10_, delegation11_.className as className10_, delegation11_.configuration as configur4_10_, join?mpl12_.id as id11_, join?mpl12_.delegation as delegation11_, join?mpl12_.correspondingFork as corresp11_11_, join?mpl12_.name as name11_, join?mpl12_.description as descript4_11_, join?mpl12_.definition as definition11_, delegation13_.id as id12_, delegation13_.definition as definition12_, delegation13_.className as className12_, delegation13_.configuration as configur4_12_, fork?mpl14_.id as id13_, fork?mpl14_.delegation as delegation13_, fork?mpl14_.correspondingJoin as corresp10_13_, fork?mpl14_.name as name13_, fork?mpl14_.description as descript4_13_, fork?mpl14_.definition as definition13_, delegation15_.id as id14_, delegation15_.definition as definition14_, delegation15_.className as className14_, delegation15_.configuration as configur4_14_, delegation16_.id as id15_, delegation16_.definition as definition15_, delegation16_.className as className15_, delegation16_.configuration as configur4_15_ from JBPM_TRANSITION leavingtra0_, JBPM_DEFINITION definition1_, JBPM_DEFINITION definition2_, JBPM_NODE startstate3_, JBPM_SWIMLANE swimlane?m4_, JBPM_DELEGATION delegation5_, JBPM_NODE endstate?m6_, JBPM_NODE endstate?m7_, JBPM_NODE node?mpl8_, JBPM_DELEGATION delegation9_, JBPM_DELEGATION delegation10_, JBPM_DELEGATION delegation11_, JBPM_NODE join?mpl12_, JBPM_DELEGATION delegation13_, JBPM_NODE fork?mpl14_, JBPM_DELEGATION delegation15_, JBPM_DELEGATION delegation16_ where leavingtra0_.from_=? and leavingtra0_.definition *= definition1_.id and definition1_.definition *= definition2_.id and definition2_.startState *= startstate3_.id and startstate3_.swimlane *= swimlane?m4_.id and swimlane?m4_.delegation *= delegation5_.id and definition2_.endState *= endstate?m6_.id and definition1_.endState *= endstate?m7_.id and leavingtra0_.to_ *= node?mpl8_.id and node?mpl8_.delegation *= delegation9_.id and node?mpl8_.delegation *= delegation10_.id and node?mpl8_.delegation *= delegation11_.id and node?mpl8_.correspondingJoin *= join?mpl12_.id and join?mpl12_.delegation *= delegation13_.id and join?mpl12_.correspondingFork *= fork?mpl14_.id and fork?mpl14_.delegation *= delegation15_.id and node?mpl8_.delegation *= delegation16_.id
              2005-12-29 17:01:52,808 INFO [STDOUT] 17:01:52,808 debug [Loader] result set contains (possibly empty) collection: [org.jbpm.model.definition.impl.NodeImpl.leavingTransitions#13107241]
              2005-12-29 17:01:52,808 INFO [STDOUT] 17:01:52,808 debug [Loader] result row: 13107224, null, null, null, null, null, 13107238, 13107230, null, null, null, null, null, null, null, null, 13107242
              2005-12-29 17:01:52,808 INFO [STDOUT] 17:01:52,808 debug [Loader] found row of collection: [org.jbpm.model.definition.impl.NodeImpl.leavingTransitions#13107241]
              2005-12-29 17:01:52,808 INFO [STDOUT] 17:01:52,808 debug [SessionImpl] resolving associations for [org.jbpm.model.definition.impl.StateImpl#13107230]
              2005-12-29 17:01:52,808 INFO [STDOUT] 17:01:52,808 debug [SQL] select swimlane?m0_.id as id7_, swimlane?m0_.name as name7_, swimlane?m0_.description as descript3_7_, swimlane?m0_.definition as definition7_, swimlane?m0_.delegation as delegation7_, definition1_.id as id0_, definition1_.name as name0_, definition1_.description as descript3_0_, definition1_.definition as definition0_, definition1_.version_ as version_0_, definition1_.startState as startState0_, definition1_.endState as endState0_, definition2_.id as id1_, definition2_.name as name1_, definition2_.description as descript3_1_, definition2_.definition as definition1_, definition2_.version_ as version_1_, definition2_.startState as startState1_, definition2_.endState as endState1_, startstate3_.id as id2_, startstate3_.swimlane as swimlane2_, startstate3_.assignmentRequired as assignme7_2_, startstate3_.stateAuthentication as stateAut8_2_, startstate3_.name as name2_, startstate3_.description as descript4_2_, startstate3_.definition as definition2_, endstate?m4_.id as id3_, endstate?m4_.swimlane as swimlane3_, endstate?m4_.assignmentRequired as assignme7_3_, endstate?m4_.stateAuthentication as stateAut8_3_, endstate?m4_.name as name3_, endstate?m4_.description as descript4_3_, endstate?m4_.definition as definition3_, startstate5_.id as id4_, startstate5_.swimlane as swimlane4_, startstate5_.assignmentRequired as assignme7_4_, startstate5_.stateAuthentication as stateAut8_4_, startstate5_.name as name4_, startstate5_.description as descript4_4_, startstate5_.definition as definition4_, endstate?m6_.id as id5_, endstate?m6_.swimlane as swimlane5_, endstate?m6_.assignmentRequired as assignme7_5_, endstate?m6_.stateAuthentication as stateAut8_5_, endstate?m6_.name as name5_, endstate?m6_.description as descript4_5_, endstate?m6_.definition as definition5_, delegation7_.id as id6_, delegation7_.definition as definition6_, delegation7_.className as className6_, delegation7_.configuration as configur4_6_ from JBPM_SWIMLANE swimlane?m0_, JBPM_DEFINITION definition1_, JBPM_DEFINITION definition2_, JBPM_NODE startstate3_, JBPM_NODE endstate?m4_, JBPM_NODE startstate5_, JBPM_NODE endstate?m6_, JBPM_DELEGATION delegation7_ where swimlane?m0_.id=? and swimlane?m0_.definition *= definition1_.id and definition1_.definition *= definition2_.id and definition2_.startState *= startstate3_.id and definition2_.endState *= endstate?m4_.id and definition1_.startState *= startstate5_.id and definition1_.endState *= endstate?m6_.id and swimlane?m0_.delegation *= delegation7_.id
              2005-12-29 17:01:52,828 INFO [STDOUT] 17:01:52,828 debug [Loader] result row: 13107224, null, null, null, 13107241, 13107238, 13107227, 13107226
              2005-12-29 17:01:52,838 INFO [STDOUT] 17:01:52,838 debug [SessionImpl] resolving associations for [org.jbpm.model.definition.impl.DelegationImpl#13107227]
              2005-12-29 17:01:52,838 INFO [STDOUT] 17:01:52,838 debug [SessionImpl] done materializing entity [org.jbpm.model.definition.impl.DelegationImpl#13107227]
              2005-12-29 17:01:52,838 INFO [STDOUT] 17:01:52,838 debug [SessionImpl] resolving associations for [org.jbpm.model.definition.impl.SwimlaneImpl#13107226]
              2005-12-29 17:01:52,838 INFO [STDOUT] 17:01:52,838 debug [SessionImpl] done materializing entity [org.jbpm.model.definition.impl.SwimlaneImpl#13107226]
              2005-12-29 17:01:52,838 INFO [STDOUT] 17:01:52,838 debug [SessionImpl] done materializing entity [org.jbpm.model.definition.impl.StateImpl#13107230]
              2005-12-29 17:01:52,838 INFO [STDOUT] 17:01:52,838 debug [SessionImpl] resolving associations for [org.jbpm.model.definition.impl.TransitionImpl#13107242]
              2005-12-29 17:01:52,838 INFO [STDOUT] 17:01:52,838 debug [SessionImpl] done materializing entity [org.jbpm.model.definition.impl.TransitionImpl#13107242]
              2005-12-29 17:01:52,838 INFO [STDOUT] 17:01:52,838 debug [SessionImpl] 1 collections were found in result set
              2005-12-29 17:01:52,838 INFO [STDOUT] 17:01:52,838 debug [SessionImpl] collection fully initialized: [org.jbpm.model.definition.impl.NodeImpl.leavingTransitions#13107241]
              2005-12-29 17:01:52,838 INFO [STDOUT] 17:01:52,838 debug [SessionImpl] 1 collections initialized
              2005-12-29 17:01:52,838 INFO [STDOUT] 17:01:52,838 debug [SessionImpl] initializing non-lazy collections
              2005-12-29 17:01:52,838 INFO [STDOUT] 17:01:52,838 debug [SQL] select actions0_.element as element__, actions0_.id as id__, actions0_.index_ as index___, actions0_.id as id7_, actions0_.element_type as element_2_7_, actions0_.element as element7_, actions0_.index_ as index_7_, actions0_.eventType as eventType7_, actions0_.actionDelegation as actionDe6_7_, delegation1_.id as id0_, delegation1_.definition as definition0_, delegation1_.className as className0_, delegation1_.configuration as configur4_0_, definition2_.id as id1_, definition2_.name as name1_, definition2_.description as descript3_1_, definition2_.definition as definition1_, definition2_.version_ as version_1_, definition2_.startState as startState1_, definition2_.endState as endState1_, definition3_.id as id2_, definition3_.name as name2_, definition3_.description as descript3_2_, definition3_.definition as definition2_, definition3_.version_ as version_2_, definition3_.startState as startState2_, definition3_.endState as endState2_, startstate4_.id as id3_, startstate4_.swimlane as swimlane3_, startstate4_.assignmentRequired as assignme7_3_, startstate4_.stateAuthentication as stateAut8_3_, startstate4_.name as name3_, startstate4_.description as descript4_3_, startstate4_.definition as definition3_, swimlane?m5_.id as id4_, swimlane?m5_.name as name4_, swimlane?m5_.description as descript3_4_, swimlane?m5_.definition as definition4_, swimlane?m5_.delegation as delegation4_, endstate?m6_.id as id5_, endstate?m6_.swimlane as swim


              • 4. Re: executionService.close() gives error all the time
                kukeltje

                Nice, but I still do not see errors here.