1 Reply Latest reply on Jun 20, 2011 12:29 PM by saig0

    [jBPM4.4] Oracle-DB close stream after deploy and access a deployment

    saig0 Newbie

      Hi!

       

      I'm using jBPM 4.4 with an Oracle-DB (Oracle Database 10g Express Edition Release 10.2.0.1.0 + ojdbc14-10.2.0.4 driver) and found a bug with the repository service.

      I'm able to deploy a new process definition and start a new process. But when I try to delete this deployment, I get the SQL-Exception: stream closed!  The same exception throws while call methode 'getResourceAsStream()' and 'getResourceNames()'.

       

      I tried with other DBs and don't get this exception.

      Can anybody help me to solve this problem?

       

       

      Test:

       @Test
       public void testDeploymentAndStartProcess()
          {
              assertDeploymentsWithCount(0);
      
              String deploymentId = deployProcess();
              assertDeploymentsWithCount(1);
      
              String processInstanceId = startProcess("CustomStateProcess");
              assertTrue(isRunning(processInstanceId));
      
              repositoryService.deleteDeploymentCascade(deploymentId);     // this fail!
              assertDeploymentsWithCount(0);
          }
      
       private void assertDeploymentsWithCount(int count)
          {
              List<Deployment> deployments = repositoryService.createDeploymentQuery().list();
              assertEquals(count, deployments.size());
          }
      

       

      Exception:

      org.hibernate.exception.GenericJDBCException: could not initialize a collection: [org.jbpm.pvm.internal.repository.DeploymentImpl.resources#160001]
                at org.hibernate.exception.SQLStateConverter.handledNonSpecificException(SQLStateConverter.java:140)
                at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:128)
                at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:66)
                at org.hibernate.loader.Loader.loadCollection(Loader.java:2089)
                at org.hibernate.loader.collection.CollectionLoader.initialize(CollectionLoader.java:62)
                at org.hibernate.persister.collection.AbstractCollectionPersister.initialize(AbstractCollectionPersister.java:628)
                at org.hibernate.event.def.DefaultInitializeCollectionEventListener.onInitializeCollection(DefaultInitializeCollectionEventListener.java:83)
                at org.hibernate.impl.SessionImpl.initializeCollection(SessionImpl.java:1853)
                at org.hibernate.collection.AbstractPersistentCollection.initialize(AbstractPersistentCollection.java:369)
                at org.hibernate.collection.AbstractPersistentCollection.read(AbstractPersistentCollection.java:111)
                at org.hibernate.collection.PersistentMap.values(PersistentMap.java:257)
                at org.hibernate.type.MapType.getElementsIterator(MapType.java:62)
                at org.hibernate.type.CollectionType.getElementsIterator(CollectionType.java:225)
                at org.hibernate.engine.CascadingAction.getAllElementsIterator(CascadingAction.java:449)
                at org.hibernate.engine.CascadingAction.access$100(CascadingAction.java:51)
                at org.hibernate.engine.CascadingAction$1.getCascadableChildrenIterator(CascadingAction.java:149)
                at org.hibernate.engine.Cascade.cascadeCollectionElements(Cascade.java:423)
                at org.hibernate.engine.Cascade.cascadeCollection(Cascade.java:362)
                at org.hibernate.engine.Cascade.cascadeAssociation(Cascade.java:338)
                at org.hibernate.engine.Cascade.cascadeProperty(Cascade.java:204)
                at org.hibernate.engine.Cascade.cascade(Cascade.java:161)
                at org.hibernate.event.def.DefaultDeleteEventListener.cascadeBeforeDelete(DefaultDeleteEventListener.java:329)
                at org.hibernate.event.def.DefaultDeleteEventListener.deleteEntity(DefaultDeleteEventListener.java:268)
                at org.hibernate.event.def.DefaultDeleteEventListener.onDelete(DefaultDeleteEventListener.java:163)
                at org.hibernate.event.def.DefaultDeleteEventListener.onDelete(DefaultDeleteEventListener.java:74)
                at org.hibernate.impl.SessionImpl.fireDelete(SessionImpl.java:948)
                at org.hibernate.impl.SessionImpl.delete(SessionImpl.java:926)
                at org.jbpm.pvm.internal.cmd.DeleteDeploymentCmd.execute(DeleteDeploymentCmd.java:88)
                at org.jbpm.pvm.internal.svc.DefaultCommandService.execute(DefaultCommandService.java:42)
                at org.jbpm.pvm.internal.tx.StandardTransactionInterceptor.execute(StandardTransactionInterceptor.java:50)
                at org.jbpm.pvm.internal.svc.EnvironmentInterceptor.executeInNewEnvironment(EnvironmentInterceptor.java:53)
                at org.jbpm.pvm.internal.svc.EnvironmentInterceptor.execute(EnvironmentInterceptor.java:40)
                at org.jbpm.pvm.internal.svc.RetryInterceptor.execute(RetryInterceptor.java:56)
                at org.jbpm.pvm.internal.svc.SkipInterceptor.execute(SkipInterceptor.java:43)
                at org.jbpm.pvm.internal.repository.RepositoryServiceImpl.deleteDeploymentCascade(RepositoryServiceImpl.java:71)
                at services.RepositoryServiceTest.testDeploymentAndStartProcess(RepositoryServiceTest.java:39)
                at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
                at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
                at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
                at java.lang.reflect.Method.invoke(Unknown Source)
                at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
                at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
                at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
                at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
                at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
                at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
                at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
                at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
                at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
                at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
                at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
                at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
                at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
                at org.eclipse.jdt.internal.junit4.runner.JUnit4TestReference.run(JUnit4TestReference.java:49)
                at org.eclipse.jdt.internal.junit.runner.TestExecution.run(TestExecution.java:38)
                at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRunner.java:467)
                at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRunner.java:683)
                at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.run(RemoteTestRunner.java:390)
                at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.main(RemoteTestRunner.java:197)
      Caused by: java.sql.SQLException: Stream wurde schon geschlossen
                at oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:112)
                at oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:146)
                at oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:208)
                at oracle.jdbc.driver.LongAccessor.getCharacterStream(LongAccessor.java:340)
                at oracle.jdbc.driver.OracleResultSetImpl.getCharacterStream(OracleResultSetImpl.java:1519)
                at oracle.jdbc.driver.OracleResultSet.getCharacterStream(OracleResultSet.java:2007)
                at org.hibernate.type.AbstractLongStringType.get(AbstractLongStringType.java:54)
                at org.hibernate.type.NullableType.nullSafeGet(NullableType.java:186)
                at org.hibernate.type.NullableType.nullSafeGet(NullableType.java:175)
                at org.hibernate.persister.collection.AbstractCollectionPersister.readIndex(AbstractCollectionPersister.java:769)
                at org.hibernate.collection.PersistentMap.readFrom(PersistentMap.java:281)
                at org.hibernate.loader.Loader.readCollectionElement(Loader.java:1072)
                at org.hibernate.loader.Loader.readCollectionElements(Loader.java:690)
                at org.hibernate.loader.Loader.getRowFromResultSet(Loader.java:630)
                at org.hibernate.loader.Loader.doQuery(Loader.java:745)
                at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:270)
                at org.hibernate.loader.Loader.loadCollection(Loader.java:2082)
                ... 55 more
      

       

      Log:

      07.06.2011 11:17:02 org.hibernate.cfg.Environment <clinit>
      INFO: Hibernate 3.5.6-Final
      07.06.2011 11:17:02 org.hibernate.cfg.Environment <clinit>
      INFO: hibernate.properties not found
      07.06.2011 11:17:02 org.hibernate.cfg.Environment buildBytecodeProvider
      INFO: Bytecode provider name : javassist
      07.06.2011 11:17:02 org.hibernate.cfg.Environment <clinit>
      INFO: using JDK 1.4 java.sql.Timestamp handling
      07.06.2011 11:17:02 org.hibernate.cfg.Configuration configure
      INFO: configuring from resource: jbpm.hibernate.cfg.xml
      07.06.2011 11:17:02 org.hibernate.cfg.Configuration getConfigurationInputStream
      INFO: Configuration resource: jbpm.hibernate.cfg.xml
      07.06.2011 11:17:03 org.hibernate.cfg.Configuration addResource
      INFO: Reading mappings from resource : jbpm.repository.hbm.xml
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.repository.DeploymentImpl -> JBPM4_DEPLOYMENT
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.repository.DeploymentProperty -> JBPM4_DEPLOYPROP
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.id.PropertyImpl -> JBPM4_PROPERTY
      07.06.2011 11:17:03 org.hibernate.cfg.Configuration addResource
      INFO: Reading mappings from resource : jbpm.execution.hbm.xml
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.model.ExecutionImpl -> JBPM4_EXECUTION
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.type.Variable -> JBPM4_VARIABLE
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.type.variable.BlobVariable -> JBPM4_VARIABLE
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.type.variable.DateVariable -> JBPM4_VARIABLE
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.type.variable.DoubleVariable -> JBPM4_VARIABLE
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.type.variable.HibernateLongVariable -> JBPM4_VARIABLE
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.type.variable.HibernateStringVariable -> JBPM4_VARIABLE
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.type.variable.LongVariable -> JBPM4_VARIABLE
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.type.variable.NullVariable -> JBPM4_VARIABLE
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.type.variable.StringVariable -> JBPM4_VARIABLE
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.type.variable.TextVariable -> JBPM4_VARIABLE
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.lob.Lob -> JBPM4_LOB
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.job.JobImpl -> JBPM4_JOB
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.job.MessageImpl -> JBPM4_JOB
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.model.op.ExecuteActivityMessage -> JBPM4_JOB
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.model.op.ExecuteEventListenerMessage -> JBPM4_JOB
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.job.CommandMessage -> JBPM4_JOB
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.job.TimerImpl -> JBPM4_JOB
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.job.StartProcessTimer -> JBPM4_JOB
      07.06.2011 11:17:03 org.hibernate.cfg.Configuration addResource
      INFO: Reading mappings from resource : jbpm.history.hbm.xml
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.history.model.HistoryProcessInstanceImpl -> JBPM4_HIST_PROCINST
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.history.model.HistoryActivityInstanceImpl -> JBPM4_HIST_ACTINST
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.history.model.HistoryAutomaticInstanceImpl -> JBPM4_HIST_ACTINST
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.history.model.HistoryDecisionInstanceImpl -> JBPM4_HIST_ACTINST
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.history.model.HistoryTaskInstanceImpl -> JBPM4_HIST_ACTINST
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.history.model.HistoryTaskImpl -> JBPM4_HIST_TASK
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.history.model.HistoryVariableImpl -> JBPM4_HIST_VAR
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.history.model.HistoryDetailImpl -> JBPM4_HIST_DETAIL
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.history.model.HistoryCommentImpl -> JBPM4_HIST_DETAIL
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.history.model.HistoryProcessInstanceMigrationImpl -> JBPM4_HIST_DETAIL
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.history.model.HistoryPriorityUpdateImpl -> JBPM4_HIST_DETAIL
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.history.model.HistoryTaskAssignmentImpl -> JBPM4_HIST_DETAIL
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.history.model.HistoryTaskDuedateUpdateImpl -> JBPM4_HIST_DETAIL
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindSubclass
      INFO: Mapping subclass: org.jbpm.pvm.internal.history.model.HistoryVariableUpdateImpl -> JBPM4_HIST_DETAIL
      07.06.2011 11:17:03 org.hibernate.cfg.Configuration addResource
      INFO: Reading mappings from resource : jbpm.task.hbm.xml
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.task.TaskImpl -> JBPM4_TASK
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.task.ParticipationImpl -> JBPM4_PARTICIPATION
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.task.SwimlaneImpl -> JBPM4_SWIMLANE
      07.06.2011 11:17:03 org.hibernate.cfg.Configuration addResource
      INFO: Reading mappings from resource : jbpm.identity.hbm.xml
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.identity.impl.UserImpl -> JBPM4_ID_USER
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.identity.impl.MembershipImpl -> JBPM4_ID_MEMBERSHIP
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindRootPersistentClassCommonValues
      INFO: Mapping class: org.jbpm.pvm.internal.identity.impl.GroupImpl -> JBPM4_ID_GROUP
      07.06.2011 11:17:03 org.hibernate.cfg.Configuration doConfigure
      INFO: Configured SessionFactory: null
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.repository.DeploymentImpl.resources -> JBPM4_LOB
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.repository.DeploymentImpl.objectProperties -> JBPM4_DEPLOYPROP
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.model.ExecutionImpl.variables -> JBPM4_VARIABLE
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.model.ExecutionImpl.systemVariables -> JBPM4_VARIABLE
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.model.ExecutionImpl.executions -> JBPM4_EXECUTION
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.model.ExecutionImpl.swimlanes -> JBPM4_SWIMLANE
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.history.model.HistoryProcessInstanceImpl.historyActivityInstances -> JBPM4_HIST_ACTINST
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.history.model.HistoryProcessInstanceImpl.historyVariables -> JBPM4_HIST_VAR
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.history.model.HistoryProcessInstanceImpl.details -> JBPM4_HIST_DETAIL
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.history.model.HistoryActivityInstanceImpl.details -> JBPM4_HIST_DETAIL
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.history.model.HistoryTaskImpl.details -> JBPM4_HIST_DETAIL
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.history.model.HistoryTaskImpl.historyVariables -> JBPM4_HIST_VAR
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.history.model.HistoryTaskImpl.subTasks -> JBPM4_HIST_TASK
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.history.model.HistoryVariableImpl.details -> JBPM4_HIST_DETAIL
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.history.model.HistoryCommentImpl.replies -> JBPM4_HIST_DETAIL
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.task.TaskImpl.participations -> JBPM4_PARTICIPATION
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.task.TaskImpl.variables -> JBPM4_VARIABLE
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.task.TaskImpl.subTasks -> JBPM4_TASK
      07.06.2011 11:17:03 org.hibernate.cfg.HbmBinder bindCollectionSecondPass
      INFO: Mapping collection: org.jbpm.pvm.internal.task.SwimlaneImpl.participations -> JBPM4_PARTICIPATION
      07.06.2011 11:17:03 org.hibernate.connection.DriverManagerConnectionProvider configure
      INFO: Using Hibernate built-in connection pool (not for production use!)
      07.06.2011 11:17:03 org.hibernate.connection.DriverManagerConnectionProvider configure
      INFO: Hibernate connection pool size: 20
      07.06.2011 11:17:03 org.hibernate.connection.DriverManagerConnectionProvider configure
      INFO: autocommit mode: false
      07.06.2011 11:17:03 org.hibernate.connection.DriverManagerConnectionProvider configure
      INFO: using driver: oracle.jdbc.OracleDriver at URL: jdbc:oracle:thin:@10.96.20.194:1521:XE
      07.06.2011 11:17:03 org.hibernate.connection.DriverManagerConnectionProvider configure
      INFO: connection properties: {user=osslerp, password=****}
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: RDBMS: Oracle, version: Oracle Database 10g Express Edition Release 10.2.0.1.0 - Production
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: JDBC driver: Oracle JDBC driver, version: 10.2.0.4.0
      07.06.2011 11:17:03 org.hibernate.dialect.Dialect <init>
      INFO: Using dialect: org.hibernate.dialect.Oracle10gDialect
      07.06.2011 11:17:03 org.hibernate.engine.jdbc.JdbcSupportLoader useContextualLobCreation
      INFO: Disabling contextual LOB creation as createClob() method threw error : java.lang.reflect.InvocationTargetException
      07.06.2011 11:17:03 org.hibernate.transaction.TransactionFactoryFactory buildTransactionFactory
      INFO: Using default transaction strategy (direct JDBC transactions)
      07.06.2011 11:17:03 org.hibernate.transaction.TransactionManagerLookupFactory getTransactionManagerLookup
      INFO: No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Automatic flush during beforeCompletion(): disabled
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Automatic session close at end of transaction: disabled
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: JDBC batch size: 15
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: JDBC batch updates for versioned data: disabled
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Scrollable result sets: enabled
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: JDBC3 getGeneratedKeys(): disabled
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Connection release mode: auto
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Default batch fetch size: 1
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Generate SQL with comments: disabled
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Order SQL updates by primary key: disabled
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Order SQL inserts for batching: disabled
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory createQueryTranslatorFactory
      INFO: Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
      07.06.2011 11:17:03 org.hibernate.hql.ast.ASTQueryTranslatorFactory <init>
      INFO: Using ASTQueryTranslatorFactory
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Query language substitutions: {}
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: JPA-QL strict compliance: disabled
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Second-level cache: enabled
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Query cache: disabled
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory createRegionFactory
      INFO: Cache region factory : org.hibernate.cache.impl.NoCachingRegionFactory
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Optimize cache for minimal puts: disabled
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Structured second-level cache entries: disabled
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Statistics: disabled
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Deleted entity synthetic identifier rollback: disabled
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Default entity-mode: pojo
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Named query checking : enabled
      07.06.2011 11:17:03 org.hibernate.cfg.SettingsFactory buildSettings
      INFO: Check Nullability in Core (should be disabled when Bean Validation is on): enabled
      07.06.2011 11:17:03 org.hibernate.impl.SessionFactoryImpl <init>
      INFO: building session factory
      07.06.2011 11:17:04 org.hibernate.impl.SessionFactoryObjectFactory addInstance
      INFO: Not binding factory to JNDI, no JNDI name configured
      07.06.2011 11:17:05 org.hibernate.type.NullableType nullSafeGet
      INFO: could not read column value from result set: NAME5_1_; Stream wurde schon geschlossen
      07.06.2011 11:17:05 org.hibernate.util.JDBCExceptionReporter logExceptions
      WARNUNG: SQL Error: 17027, SQLState: null
      07.06.2011 11:17:05 org.hibernate.util.JDBCExceptionReporter logExceptions
      SCHWERWIEGEND: Stream wurde schon geschlossen
      07.06.2011 11:17:05 org.hibernate.engine.loading.LoadContexts cleanup
      WARNUNG: fail-safe cleanup (collections) : org.hibernate.engine.loading.CollectionLoadContext@17c7988<rs=oracle.jdbc.driver.OracleResultSetImpl@1f2be27>
      07.06.2011 11:17:05 org.hibernate.engine.loading.CollectionLoadContext cleanup
      WARNUNG: On CollectionLoadContext#cleanup, localLoadingCollectionKeys contained [1] entries