SuccessConsole Output

Skipping 204 KB.. Full Log
actory]
20190612-23:33:22: [INFO]: Using provided datasource [org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider]
20190612-23:33:22: [INFO]: Using dialect: org.hibernate.dialect.H2Dialect [org.hibernate.dialect.Dialect]
20190612-23:33:22: [INFO]: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4 [org.hibernate.engine.jdbc.JdbcSupportLoader]
20190612-23:33:22: [INFO]: Database ->
       name : H2
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Driver ->
       name : H2 JDBC Driver
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Using default transaction strategy (direct JDBC transactions) [org.hibernate.transaction.TransactionFactoryFactory]
20190612-23:33:22: [INFO]: No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended) [org.hibernate.transaction.TransactionManagerLookupFactory]
20190612-23:33:22: [INFO]: Automatic flush during beforeCompletion(): disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Automatic session close at end of transaction: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JDBC batch size: 15 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JDBC batch updates for versioned data: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Scrollable result sets: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JDBC3 getGeneratedKeys(): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Connection release mode: auto [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Default batch fetch size: 1 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Generate SQL with comments: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Order SQL updates by primary key: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Order SQL inserts for batching: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Using ASTQueryTranslatorFactory [org.hibernate.hql.ast.ASTQueryTranslatorFactory]
20190612-23:33:22: [INFO]: Query language substitutions: {} [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JPA-QL strict compliance: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Second-level cache: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Query cache: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Cache region factory : org.hibernate.cache.impl.NoCachingRegionFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Optimize cache for minimal puts: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Structured second-level cache entries: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Statistics: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Deleted entity synthetic identifier rollback: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Default entity-mode: pojo [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Named query checking : enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Check Nullability in Core (should be disabled when Bean Validation is on): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: building session factory [org.hibernate.impl.SessionFactoryImpl]
20190612-23:33:22: [INFO]: Type registration [wrapper_characters_clob] overrides previous : org.hibernate.type.CharacterArrayClobType@182e4365 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [wrapper_materialized_blob] overrides previous : org.hibernate.type.WrappedMaterializedBlobType@789dd6bf [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [materialized_clob] overrides previous : org.hibernate.type.MaterializedClobType@1e6d30c0 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [java.sql.Blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [materialized_blob] overrides previous : org.hibernate.type.MaterializedBlobType@69d667a5 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [java.sql.Clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [characters_clob] overrides previous : org.hibernate.type.PrimitiveCharacterArrayClobType@8f4b803 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Not binding factory to JNDI, no JNDI name configured [org.hibernate.impl.SessionFactoryObjectFactory]
20190612-23:33:22: [INFO]: Running hbm2ddl schema update [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: fetching database metadata [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: updating schema [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TASK_QUEUE [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: columns: [nextexecution, trycount, pid, id, status] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: indexes: [index_exec_task, primary_key_1, index_pid_task] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TRY_HISTORY [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: columns: [lastreplicationattemptdate, replicationattempts, pid, id, nodeid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: indexes: [primary_key_b, index_pid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: schema update complete [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@26a9c6df: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationTaskRepository,replicationAttemptHistoryRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Closing org.springframework.context.annotation.AnnotationConfigApplicationContext@74bfdd66: startup date [Wed Jun 12 23:33:22 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:22: [INFO]: Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@26a9c6df: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationTaskRepository,replicationAttemptHistoryRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Closing JPA EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:22: [INFO]: closing [org.hibernate.impl.SessionFactoryImpl]
20190612-23:33:22: [INFO]: Refreshing org.springframework.context.annotation.AnnotationConfigApplicationContext@54f25b42: startup date [Wed Jun 12 23:33:22 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:22: [INFO]: Overriding bean definition for bean 'replicationAttemptHistoryRepository': replacing [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Overriding bean definition for bean 'replicationTaskRepository': replacing [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Overriding bean definition for bean 'dataSource': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationPostgresRepositoryFactory; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationH2RepositoryFactory; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class org.dataone.cn.data.repository.ReplicationH2RepositoryFactory] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Overriding bean definition for bean 'jpaVendorAdapter': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationPostgresRepositoryFactory; factoryMethodName=jpaVendorAdapter; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationH2RepositoryFactory; factoryMethodName=jpaVendorAdapter; initMethodName=null; destroyMethodName=(inferred); defined in class org.dataone.cn.data.repository.ReplicationH2RepositoryFactory] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Creating embedded database 'testdb' [org.springframework.jdbc.datasource.embedded.EmbeddedDatabaseFactory]
20190612-23:33:22: [INFO]: Building JPA container EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:22: [INFO]: Processing PersistenceUnitInfo [
	name: default
	...] [org.hibernate.ejb.Ejb3Configuration]
20190612-23:33:22: [INFO]: Binding entity from annotated class: org.dataone.cn.data.repository.ReplicationAttemptHistory [org.hibernate.cfg.AnnotationBinder]
20190612-23:33:22: [INFO]: Bind entity org.dataone.cn.data.repository.ReplicationAttemptHistory on table replication_try_history [org.hibernate.cfg.annotations.EntityBinder]
20190612-23:33:22: [INFO]: Binding entity from annotated class: org.dataone.cn.data.repository.ReplicationTask [org.hibernate.cfg.AnnotationBinder]
20190612-23:33:22: [INFO]: Bind entity org.dataone.cn.data.repository.ReplicationTask on table replication_task_queue [org.hibernate.cfg.annotations.EntityBinder]
20190612-23:33:22: [INFO]: Hibernate Validator not found: ignoring [org.hibernate.cfg.Configuration]
20190612-23:33:22: [INFO]: Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled. [org.hibernate.cfg.search.HibernateSearchEventListenerRegister]
20190612-23:33:22: [INFO]: Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider [org.hibernate.connection.ConnectionProviderFactory]
20190612-23:33:22: [INFO]: Using provided datasource [org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider]
20190612-23:33:22: [INFO]: Using dialect: org.hibernate.dialect.H2Dialect [org.hibernate.dialect.Dialect]
20190612-23:33:22: [INFO]: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4 [org.hibernate.engine.jdbc.JdbcSupportLoader]
20190612-23:33:22: [INFO]: Database ->
       name : H2
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Driver ->
       name : H2 JDBC Driver
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Using default transaction strategy (direct JDBC transactions) [org.hibernate.transaction.TransactionFactoryFactory]
20190612-23:33:22: [INFO]: No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended) [org.hibernate.transaction.TransactionManagerLookupFactory]
20190612-23:33:22: [INFO]: Automatic flush during beforeCompletion(): disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Automatic session close at end of transaction: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JDBC batch size: 15 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JDBC batch updates for versioned data: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Scrollable result sets: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JDBC3 getGeneratedKeys(): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Connection release mode: auto [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Default batch fetch size: 1 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Generate SQL with comments: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Order SQL updates by primary key: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Order SQL inserts for batching: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Using ASTQueryTranslatorFactory [org.hibernate.hql.ast.ASTQueryTranslatorFactory]
20190612-23:33:22: [INFO]: Query language substitutions: {} [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JPA-QL strict compliance: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Second-level cache: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Query cache: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Cache region factory : org.hibernate.cache.impl.NoCachingRegionFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Optimize cache for minimal puts: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Structured second-level cache entries: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Statistics: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Deleted entity synthetic identifier rollback: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Default entity-mode: pojo [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Named query checking : enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Check Nullability in Core (should be disabled when Bean Validation is on): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: building session factory [org.hibernate.impl.SessionFactoryImpl]
20190612-23:33:22: [INFO]: Type registration [wrapper_characters_clob] overrides previous : org.hibernate.type.CharacterArrayClobType@182e4365 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [wrapper_materialized_blob] overrides previous : org.hibernate.type.WrappedMaterializedBlobType@789dd6bf [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [materialized_clob] overrides previous : org.hibernate.type.MaterializedClobType@1e6d30c0 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [java.sql.Blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [materialized_blob] overrides previous : org.hibernate.type.MaterializedBlobType@69d667a5 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [java.sql.Clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [characters_clob] overrides previous : org.hibernate.type.PrimitiveCharacterArrayClobType@8f4b803 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Not binding factory to JNDI, no JNDI name configured [org.hibernate.impl.SessionFactoryObjectFactory]
20190612-23:33:22: [INFO]: Running hbm2ddl schema update [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: fetching database metadata [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: updating schema [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TASK_QUEUE [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: columns: [nextexecution, trycount, pid, id, status] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: indexes: [index_exec_task, primary_key_1, index_pid_task] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TRY_HISTORY [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: columns: [lastreplicationattemptdate, replicationattempts, pid, id, nodeid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: indexes: [primary_key_b, index_pid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: schema update complete [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@46e6458d: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationAttemptHistoryRepository,replicationTaskRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
Results found with findByPid('foo_pid'):
--------------------------------------------
ReplicationTask [id=9, pid=foo_pid, status=NEW, tryCount=0, nextExecution=06/12/2019:23:33:22:658]
20190612-23:33:22: [INFO]: Closing org.springframework.context.annotation.AnnotationConfigApplicationContext@54f25b42: startup date [Wed Jun 12 23:33:22 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:22: [INFO]: Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@46e6458d: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationAttemptHistoryRepository,replicationTaskRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Closing JPA EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:22: [INFO]: closing [org.hibernate.impl.SessionFactoryImpl]
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.439 sec
Running org.dataone.cn.data.repository.ReplicationAttemptHistoryRepositoryTest
20190612-23:33:22: [INFO]: Refreshing org.springframework.context.annotation.AnnotationConfigApplicationContext@27746c5e: startup date [Wed Jun 12 23:33:22 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:22: [INFO]: Overriding bean definition for bean 'replicationTaskRepository': replacing [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Overriding bean definition for bean 'replicationAttemptHistoryRepository': replacing [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Overriding bean definition for bean 'dataSource': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationPostgresRepositoryFactory; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationH2RepositoryFactory; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class org.dataone.cn.data.repository.ReplicationH2RepositoryFactory] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Overriding bean definition for bean 'jpaVendorAdapter': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationPostgresRepositoryFactory; factoryMethodName=jpaVendorAdapter; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationH2RepositoryFactory; factoryMethodName=jpaVendorAdapter; initMethodName=null; destroyMethodName=(inferred); defined in class org.dataone.cn.data.repository.ReplicationH2RepositoryFactory] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Creating embedded database 'testdb' [org.springframework.jdbc.datasource.embedded.EmbeddedDatabaseFactory]
20190612-23:33:22: [INFO]: Building JPA container EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:22: [INFO]: Processing PersistenceUnitInfo [
	name: default
	...] [org.hibernate.ejb.Ejb3Configuration]
20190612-23:33:22: [INFO]: Binding entity from annotated class: org.dataone.cn.data.repository.ReplicationAttemptHistory [org.hibernate.cfg.AnnotationBinder]
20190612-23:33:22: [INFO]: Bind entity org.dataone.cn.data.repository.ReplicationAttemptHistory on table replication_try_history [org.hibernate.cfg.annotations.EntityBinder]
20190612-23:33:22: [INFO]: Binding entity from annotated class: org.dataone.cn.data.repository.ReplicationTask [org.hibernate.cfg.AnnotationBinder]
20190612-23:33:22: [INFO]: Bind entity org.dataone.cn.data.repository.ReplicationTask on table replication_task_queue [org.hibernate.cfg.annotations.EntityBinder]
20190612-23:33:22: [INFO]: Hibernate Validator not found: ignoring [org.hibernate.cfg.Configuration]
20190612-23:33:22: [INFO]: Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled. [org.hibernate.cfg.search.HibernateSearchEventListenerRegister]
20190612-23:33:22: [INFO]: Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider [org.hibernate.connection.ConnectionProviderFactory]
20190612-23:33:22: [INFO]: Using provided datasource [org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider]
20190612-23:33:22: [INFO]: Using dialect: org.hibernate.dialect.H2Dialect [org.hibernate.dialect.Dialect]
20190612-23:33:22: [INFO]: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4 [org.hibernate.engine.jdbc.JdbcSupportLoader]
20190612-23:33:22: [INFO]: Database ->
       name : H2
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Driver ->
       name : H2 JDBC Driver
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Using default transaction strategy (direct JDBC transactions) [org.hibernate.transaction.TransactionFactoryFactory]
20190612-23:33:22: [INFO]: No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended) [org.hibernate.transaction.TransactionManagerLookupFactory]
20190612-23:33:22: [INFO]: Automatic flush during beforeCompletion(): disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Automatic session close at end of transaction: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JDBC batch size: 15 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JDBC batch updates for versioned data: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Scrollable result sets: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JDBC3 getGeneratedKeys(): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Connection release mode: auto [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Default batch fetch size: 1 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Generate SQL with comments: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Order SQL updates by primary key: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Order SQL inserts for batching: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Using ASTQueryTranslatorFactory [org.hibernate.hql.ast.ASTQueryTranslatorFactory]
20190612-23:33:22: [INFO]: Query language substitutions: {} [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JPA-QL strict compliance: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Second-level cache: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Query cache: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Cache region factory : org.hibernate.cache.impl.NoCachingRegionFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Optimize cache for minimal puts: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Structured second-level cache entries: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Statistics: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Deleted entity synthetic identifier rollback: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Default entity-mode: pojo [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Named query checking : enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Check Nullability in Core (should be disabled when Bean Validation is on): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: building session factory [org.hibernate.impl.SessionFactoryImpl]
20190612-23:33:22: [INFO]: Type registration [wrapper_characters_clob] overrides previous : org.hibernate.type.CharacterArrayClobType@182e4365 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [wrapper_materialized_blob] overrides previous : org.hibernate.type.WrappedMaterializedBlobType@789dd6bf [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [materialized_clob] overrides previous : org.hibernate.type.MaterializedClobType@1e6d30c0 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [java.sql.Blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [materialized_blob] overrides previous : org.hibernate.type.MaterializedBlobType@69d667a5 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [java.sql.Clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [characters_clob] overrides previous : org.hibernate.type.PrimitiveCharacterArrayClobType@8f4b803 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Not binding factory to JNDI, no JNDI name configured [org.hibernate.impl.SessionFactoryObjectFactory]
20190612-23:33:22: [INFO]: Running hbm2ddl schema update [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: fetching database metadata [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: updating schema [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TASK_QUEUE [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: columns: [nextexecution, trycount, pid, id, status] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: indexes: [index_exec_task, primary_key_1, index_pid_task] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TRY_HISTORY [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: columns: [lastreplicationattemptdate, replicationattempts, pid, id, nodeid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: indexes: [primary_key_b, index_pid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: schema update complete [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@2270f58d: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationAttemptHistoryRepository,replicationTaskRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
Results found with findAll():
-------------------------------
ReplicationTryHistory [id=8, pid=foo_pid, nodeId=urn:node:testNode, replication attempts=4, last attempt=06/12/2019:23:33:22:808']
ReplicationTryHistory [id=9, pid=bar_pid, nodeId=urn:node:testNode, replication attempts=2, last attempt=06/12/2019:23:33:22:809']

20190612-23:33:22: [INFO]: Closing org.springframework.context.annotation.AnnotationConfigApplicationContext@27746c5e: startup date [Wed Jun 12 23:33:22 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:22: [INFO]: Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@2270f58d: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationAttemptHistoryRepository,replicationTaskRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Closing JPA EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:22: [INFO]: closing [org.hibernate.impl.SessionFactoryImpl]
20190612-23:33:22: [INFO]: Refreshing org.springframework.context.annotation.AnnotationConfigApplicationContext@6badb08c: startup date [Wed Jun 12 23:33:22 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:22: [INFO]: Overriding bean definition for bean 'replicationAttemptHistoryRepository': replacing [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Overriding bean definition for bean 'replicationTaskRepository': replacing [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Overriding bean definition for bean 'dataSource': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationPostgresRepositoryFactory; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationH2RepositoryFactory; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class org.dataone.cn.data.repository.ReplicationH2RepositoryFactory] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Overriding bean definition for bean 'jpaVendorAdapter': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationPostgresRepositoryFactory; factoryMethodName=jpaVendorAdapter; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationH2RepositoryFactory; factoryMethodName=jpaVendorAdapter; initMethodName=null; destroyMethodName=(inferred); defined in class org.dataone.cn.data.repository.ReplicationH2RepositoryFactory] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Creating embedded database 'testdb' [org.springframework.jdbc.datasource.embedded.EmbeddedDatabaseFactory]
20190612-23:33:22: [INFO]: Building JPA container EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:22: [INFO]: Processing PersistenceUnitInfo [
	name: default
	...] [org.hibernate.ejb.Ejb3Configuration]
20190612-23:33:22: [INFO]: Binding entity from annotated class: org.dataone.cn.data.repository.ReplicationAttemptHistory [org.hibernate.cfg.AnnotationBinder]
20190612-23:33:22: [INFO]: Bind entity org.dataone.cn.data.repository.ReplicationAttemptHistory on table replication_try_history [org.hibernate.cfg.annotations.EntityBinder]
20190612-23:33:22: [INFO]: Binding entity from annotated class: org.dataone.cn.data.repository.ReplicationTask [org.hibernate.cfg.AnnotationBinder]
20190612-23:33:22: [INFO]: Bind entity org.dataone.cn.data.repository.ReplicationTask on table replication_task_queue [org.hibernate.cfg.annotations.EntityBinder]
20190612-23:33:22: [INFO]: Hibernate Validator not found: ignoring [org.hibernate.cfg.Configuration]
20190612-23:33:22: [INFO]: Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled. [org.hibernate.cfg.search.HibernateSearchEventListenerRegister]
20190612-23:33:22: [INFO]: Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider [org.hibernate.connection.ConnectionProviderFactory]
20190612-23:33:22: [INFO]: Using provided datasource [org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider]
20190612-23:33:22: [INFO]: Using dialect: org.hibernate.dialect.H2Dialect [org.hibernate.dialect.Dialect]
20190612-23:33:22: [INFO]: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4 [org.hibernate.engine.jdbc.JdbcSupportLoader]
20190612-23:33:22: [INFO]: Database ->
       name : H2
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Driver ->
       name : H2 JDBC Driver
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Using default transaction strategy (direct JDBC transactions) [org.hibernate.transaction.TransactionFactoryFactory]
20190612-23:33:22: [INFO]: No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended) [org.hibernate.transaction.TransactionManagerLookupFactory]
20190612-23:33:22: [INFO]: Automatic flush during beforeCompletion(): disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Automatic session close at end of transaction: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JDBC batch size: 15 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JDBC batch updates for versioned data: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Scrollable result sets: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JDBC3 getGeneratedKeys(): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Connection release mode: auto [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Default batch fetch size: 1 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Generate SQL with comments: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Order SQL updates by primary key: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Order SQL inserts for batching: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Using ASTQueryTranslatorFactory [org.hibernate.hql.ast.ASTQueryTranslatorFactory]
20190612-23:33:22: [INFO]: Query language substitutions: {} [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JPA-QL strict compliance: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Second-level cache: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Query cache: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Cache region factory : org.hibernate.cache.impl.NoCachingRegionFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Optimize cache for minimal puts: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Structured second-level cache entries: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Statistics: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Deleted entity synthetic identifier rollback: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Default entity-mode: pojo [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Named query checking : enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Check Nullability in Core (should be disabled when Bean Validation is on): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: building session factory [org.hibernate.impl.SessionFactoryImpl]
20190612-23:33:22: [INFO]: Type registration [wrapper_characters_clob] overrides previous : org.hibernate.type.CharacterArrayClobType@182e4365 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [wrapper_materialized_blob] overrides previous : org.hibernate.type.WrappedMaterializedBlobType@789dd6bf [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [materialized_clob] overrides previous : org.hibernate.type.MaterializedClobType@1e6d30c0 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [java.sql.Blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [materialized_blob] overrides previous : org.hibernate.type.MaterializedBlobType@69d667a5 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [java.sql.Clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [characters_clob] overrides previous : org.hibernate.type.PrimitiveCharacterArrayClobType@8f4b803 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Not binding factory to JNDI, no JNDI name configured [org.hibernate.impl.SessionFactoryObjectFactory]
20190612-23:33:22: [INFO]: Running hbm2ddl schema update [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: fetching database metadata [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: updating schema [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TASK_QUEUE [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: columns: [nextexecution, trycount, pid, id, status] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: indexes: [index_exec_task, primary_key_1, index_pid_task] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TRY_HISTORY [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: columns: [lastreplicationattemptdate, replicationattempts, pid, id, nodeid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: indexes: [primary_key_b, index_pid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: schema update complete [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@17332039: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationAttemptHistoryRepository,replicationTaskRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
page 0: ReplicationTryHistory [id=10, pid=foo_pid, nodeId=urn:node:testNode, replication attempts=4, last attempt=06/12/2019:23:33:22:910']
page 1: ReplicationTryHistory [id=11, pid=bar_pid, nodeId=urn:node:testNode, replication attempts=14, last attempt=06/12/2019:23:33:22:911']
page 2: ReplicationTryHistory [id=12, pid=foo_pid, nodeId=urn:node:testNode2, replication attempts=2, last attempt=06/12/2019:23:33:22:912']
page 3: ReplicationTryHistory [id=13, pid=bar_pid, nodeId=urn:node:testNode2, replication attempts=1, last attempt=06/12/2019:23:33:22:913']
page 4: ReplicationTryHistory [id=14, pid=foobar_pid, nodeId=urn:node:testNode, replication attempts=12, last attempt=06/12/2019:23:33:22:913']
20190612-23:33:22: [INFO]: Closing org.springframework.context.annotation.AnnotationConfigApplicationContext@6badb08c: startup date [Wed Jun 12 23:33:22 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:22: [INFO]: Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@17332039: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationAttemptHistoryRepository,replicationTaskRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Closing JPA EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:22: [INFO]: closing [org.hibernate.impl.SessionFactoryImpl]
20190612-23:33:22: [INFO]: Refreshing org.springframework.context.annotation.AnnotationConfigApplicationContext@120d13ae: startup date [Wed Jun 12 23:33:22 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:22: [INFO]: Overriding bean definition for bean 'replicationAttemptHistoryRepository': replacing [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Overriding bean definition for bean 'replicationTaskRepository': replacing [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Overriding bean definition for bean 'dataSource': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationPostgresRepositoryFactory; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationH2RepositoryFactory; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class org.dataone.cn.data.repository.ReplicationH2RepositoryFactory] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Overriding bean definition for bean 'jpaVendorAdapter': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationPostgresRepositoryFactory; factoryMethodName=jpaVendorAdapter; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationH2RepositoryFactory; factoryMethodName=jpaVendorAdapter; initMethodName=null; destroyMethodName=(inferred); defined in class org.dataone.cn.data.repository.ReplicationH2RepositoryFactory] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Creating embedded database 'testdb' [org.springframework.jdbc.datasource.embedded.EmbeddedDatabaseFactory]
20190612-23:33:22: [INFO]: Building JPA container EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:22: [INFO]: Processing PersistenceUnitInfo [
	name: default
	...] [org.hibernate.ejb.Ejb3Configuration]
20190612-23:33:22: [INFO]: Binding entity from annotated class: org.dataone.cn.data.repository.ReplicationAttemptHistory [org.hibernate.cfg.AnnotationBinder]
20190612-23:33:22: [INFO]: Bind entity org.dataone.cn.data.repository.ReplicationAttemptHistory on table replication_try_history [org.hibernate.cfg.annotations.EntityBinder]
20190612-23:33:22: [INFO]: Binding entity from annotated class: org.dataone.cn.data.repository.ReplicationTask [org.hibernate.cfg.AnnotationBinder]
20190612-23:33:22: [INFO]: Bind entity org.dataone.cn.data.repository.ReplicationTask on table replication_task_queue [org.hibernate.cfg.annotations.EntityBinder]
20190612-23:33:22: [INFO]: Hibernate Validator not found: ignoring [org.hibernate.cfg.Configuration]
20190612-23:33:22: [INFO]: Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled. [org.hibernate.cfg.search.HibernateSearchEventListenerRegister]
20190612-23:33:22: [INFO]: Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider [org.hibernate.connection.ConnectionProviderFactory]
20190612-23:33:22: [INFO]: Using provided datasource [org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider]
20190612-23:33:22: [INFO]: Using dialect: org.hibernate.dialect.H2Dialect [org.hibernate.dialect.Dialect]
20190612-23:33:22: [INFO]: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4 [org.hibernate.engine.jdbc.JdbcSupportLoader]
20190612-23:33:22: [INFO]: Database ->
       name : H2
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Driver ->
       name : H2 JDBC Driver
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Using default transaction strategy (direct JDBC transactions) [org.hibernate.transaction.TransactionFactoryFactory]
20190612-23:33:22: [INFO]: No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended) [org.hibernate.transaction.TransactionManagerLookupFactory]
20190612-23:33:22: [INFO]: Automatic flush during beforeCompletion(): disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Automatic session close at end of transaction: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JDBC batch size: 15 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JDBC batch updates for versioned data: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Scrollable result sets: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JDBC3 getGeneratedKeys(): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Connection release mode: auto [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Default batch fetch size: 1 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Generate SQL with comments: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Order SQL updates by primary key: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Order SQL inserts for batching: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Using ASTQueryTranslatorFactory [org.hibernate.hql.ast.ASTQueryTranslatorFactory]
20190612-23:33:22: [INFO]: Query language substitutions: {} [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: JPA-QL strict compliance: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Second-level cache: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Query cache: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Cache region factory : org.hibernate.cache.impl.NoCachingRegionFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Optimize cache for minimal puts: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Structured second-level cache entries: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Statistics: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Deleted entity synthetic identifier rollback: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Default entity-mode: pojo [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Named query checking : enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: Check Nullability in Core (should be disabled when Bean Validation is on): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:22: [INFO]: building session factory [org.hibernate.impl.SessionFactoryImpl]
20190612-23:33:22: [INFO]: Type registration [wrapper_characters_clob] overrides previous : org.hibernate.type.CharacterArrayClobType@182e4365 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [wrapper_materialized_blob] overrides previous : org.hibernate.type.WrappedMaterializedBlobType@789dd6bf [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [materialized_clob] overrides previous : org.hibernate.type.MaterializedClobType@1e6d30c0 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [java.sql.Blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [materialized_blob] overrides previous : org.hibernate.type.MaterializedBlobType@69d667a5 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [java.sql.Clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Type registration [characters_clob] overrides previous : org.hibernate.type.PrimitiveCharacterArrayClobType@8f4b803 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:22: [INFO]: Not binding factory to JNDI, no JNDI name configured [org.hibernate.impl.SessionFactoryObjectFactory]
20190612-23:33:22: [INFO]: Running hbm2ddl schema update [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: fetching database metadata [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: updating schema [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TASK_QUEUE [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: columns: [nextexecution, trycount, pid, id, status] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: indexes: [index_exec_task, primary_key_1, index_pid_task] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TRY_HISTORY [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: columns: [lastreplicationattemptdate, replicationattempts, pid, id, nodeid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: indexes: [primary_key_b, index_pid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:22: [INFO]: schema update complete [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:22: [INFO]: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@7cbe3a05: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationTaskRepository,replicationAttemptHistoryRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Closing org.springframework.context.annotation.AnnotationConfigApplicationContext@120d13ae: startup date [Wed Jun 12 23:33:22 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:22: [INFO]: Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@7cbe3a05: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationTaskRepository,replicationAttemptHistoryRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:22: [INFO]: Closing JPA EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:22: [INFO]: closing [org.hibernate.impl.SessionFactoryImpl]
20190612-23:33:22: [INFO]: Refreshing org.springframework.context.annotation.AnnotationConfigApplicationContext@1feb76b9: startup date [Wed Jun 12 23:33:22 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:23: [INFO]: Overriding bean definition for bean 'replicationAttemptHistoryRepository': replacing [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Overriding bean definition for bean 'replicationTaskRepository': replacing [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Overriding bean definition for bean 'dataSource': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationPostgresRepositoryFactory; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationH2RepositoryFactory; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class org.dataone.cn.data.repository.ReplicationH2RepositoryFactory] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Overriding bean definition for bean 'jpaVendorAdapter': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationPostgresRepositoryFactory; factoryMethodName=jpaVendorAdapter; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationH2RepositoryFactory; factoryMethodName=jpaVendorAdapter; initMethodName=null; destroyMethodName=(inferred); defined in class org.dataone.cn.data.repository.ReplicationH2RepositoryFactory] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Creating embedded database 'testdb' [org.springframework.jdbc.datasource.embedded.EmbeddedDatabaseFactory]
20190612-23:33:23: [INFO]: Building JPA container EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:23: [INFO]: Processing PersistenceUnitInfo [
	name: default
	...] [org.hibernate.ejb.Ejb3Configuration]
20190612-23:33:23: [INFO]: Binding entity from annotated class: org.dataone.cn.data.repository.ReplicationAttemptHistory [org.hibernate.cfg.AnnotationBinder]
20190612-23:33:23: [INFO]: Bind entity org.dataone.cn.data.repository.ReplicationAttemptHistory on table replication_try_history [org.hibernate.cfg.annotations.EntityBinder]
20190612-23:33:23: [INFO]: Binding entity from annotated class: org.dataone.cn.data.repository.ReplicationTask [org.hibernate.cfg.AnnotationBinder]
20190612-23:33:23: [INFO]: Bind entity org.dataone.cn.data.repository.ReplicationTask on table replication_task_queue [org.hibernate.cfg.annotations.EntityBinder]
20190612-23:33:23: [INFO]: Hibernate Validator not found: ignoring [org.hibernate.cfg.Configuration]
20190612-23:33:23: [INFO]: Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled. [org.hibernate.cfg.search.HibernateSearchEventListenerRegister]
20190612-23:33:23: [INFO]: Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider [org.hibernate.connection.ConnectionProviderFactory]
20190612-23:33:23: [INFO]: Using provided datasource [org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider]
20190612-23:33:23: [INFO]: Using dialect: org.hibernate.dialect.H2Dialect [org.hibernate.dialect.Dialect]
20190612-23:33:23: [INFO]: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4 [org.hibernate.engine.jdbc.JdbcSupportLoader]
20190612-23:33:23: [INFO]: Database ->
       name : H2
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Driver ->
       name : H2 JDBC Driver
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Using default transaction strategy (direct JDBC transactions) [org.hibernate.transaction.TransactionFactoryFactory]
20190612-23:33:23: [INFO]: No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended) [org.hibernate.transaction.TransactionManagerLookupFactory]
20190612-23:33:23: [INFO]: Automatic flush during beforeCompletion(): disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Automatic session close at end of transaction: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: JDBC batch size: 15 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: JDBC batch updates for versioned data: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Scrollable result sets: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: JDBC3 getGeneratedKeys(): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Connection release mode: auto [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Default batch fetch size: 1 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Generate SQL with comments: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Order SQL updates by primary key: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Order SQL inserts for batching: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Using ASTQueryTranslatorFactory [org.hibernate.hql.ast.ASTQueryTranslatorFactory]
20190612-23:33:23: [INFO]: Query language substitutions: {} [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: JPA-QL strict compliance: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Second-level cache: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Query cache: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Cache region factory : org.hibernate.cache.impl.NoCachingRegionFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Optimize cache for minimal puts: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Structured second-level cache entries: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Statistics: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Deleted entity synthetic identifier rollback: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Default entity-mode: pojo [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Named query checking : enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Check Nullability in Core (should be disabled when Bean Validation is on): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: building session factory [org.hibernate.impl.SessionFactoryImpl]
20190612-23:33:23: [INFO]: Type registration [wrapper_characters_clob] overrides previous : org.hibernate.type.CharacterArrayClobType@182e4365 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [wrapper_materialized_blob] overrides previous : org.hibernate.type.WrappedMaterializedBlobType@789dd6bf [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [materialized_clob] overrides previous : org.hibernate.type.MaterializedClobType@1e6d30c0 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [java.sql.Blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [materialized_blob] overrides previous : org.hibernate.type.MaterializedBlobType@69d667a5 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [java.sql.Clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [characters_clob] overrides previous : org.hibernate.type.PrimitiveCharacterArrayClobType@8f4b803 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Not binding factory to JNDI, no JNDI name configured [org.hibernate.impl.SessionFactoryObjectFactory]
20190612-23:33:23: [INFO]: Running hbm2ddl schema update [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:23: [INFO]: fetching database metadata [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:23: [INFO]: updating schema [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:23: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TASK_QUEUE [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: columns: [nextexecution, trycount, pid, id, status] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: indexes: [index_exec_task, primary_key_1, index_pid_task] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TRY_HISTORY [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: columns: [lastreplicationattemptdate, replicationattempts, pid, id, nodeid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: indexes: [primary_key_b, index_pid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: schema update complete [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:23: [INFO]: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@32298473: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationTaskRepository,replicationAttemptHistoryRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
Results found with findByReplicationAttempts(4):
--------------------------------------------
ReplicationTryHistory [id=15, pid=foo_pid, nodeId=urn:node:testNode, replication attempts=4, last attempt=06/12/2019:23:33:23:51']
20190612-23:33:23: [INFO]: Closing org.springframework.context.annotation.AnnotationConfigApplicationContext@1feb76b9: startup date [Wed Jun 12 23:33:22 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:23: [INFO]: Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@32298473: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationTaskRepository,replicationAttemptHistoryRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Closing JPA EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:23: [INFO]: closing [org.hibernate.impl.SessionFactoryImpl]
20190612-23:33:23: [INFO]: Refreshing org.springframework.context.annotation.AnnotationConfigApplicationContext@11120583: startup date [Wed Jun 12 23:33:23 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:23: [INFO]: Overriding bean definition for bean 'replicationAttemptHistoryRepository': replacing [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Overriding bean definition for bean 'replicationTaskRepository': replacing [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Overriding bean definition for bean 'dataSource': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationPostgresRepositoryFactory; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationH2RepositoryFactory; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class org.dataone.cn.data.repository.ReplicationH2RepositoryFactory] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Overriding bean definition for bean 'jpaVendorAdapter': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationPostgresRepositoryFactory; factoryMethodName=jpaVendorAdapter; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationH2RepositoryFactory; factoryMethodName=jpaVendorAdapter; initMethodName=null; destroyMethodName=(inferred); defined in class org.dataone.cn.data.repository.ReplicationH2RepositoryFactory] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Creating embedded database 'testdb' [org.springframework.jdbc.datasource.embedded.EmbeddedDatabaseFactory]
20190612-23:33:23: [INFO]: Building JPA container EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:23: [INFO]: Processing PersistenceUnitInfo [
	name: default
	...] [org.hibernate.ejb.Ejb3Configuration]
20190612-23:33:23: [INFO]: Binding entity from annotated class: org.dataone.cn.data.repository.ReplicationAttemptHistory [org.hibernate.cfg.AnnotationBinder]
20190612-23:33:23: [INFO]: Bind entity org.dataone.cn.data.repository.ReplicationAttemptHistory on table replication_try_history [org.hibernate.cfg.annotations.EntityBinder]
20190612-23:33:23: [INFO]: Binding entity from annotated class: org.dataone.cn.data.repository.ReplicationTask [org.hibernate.cfg.AnnotationBinder]
20190612-23:33:23: [INFO]: Bind entity org.dataone.cn.data.repository.ReplicationTask on table replication_task_queue [org.hibernate.cfg.annotations.EntityBinder]
20190612-23:33:23: [INFO]: Hibernate Validator not found: ignoring [org.hibernate.cfg.Configuration]
20190612-23:33:23: [INFO]: Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled. [org.hibernate.cfg.search.HibernateSearchEventListenerRegister]
20190612-23:33:23: [INFO]: Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider [org.hibernate.connection.ConnectionProviderFactory]
20190612-23:33:23: [INFO]: Using provided datasource [org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider]
20190612-23:33:23: [INFO]: Using dialect: org.hibernate.dialect.H2Dialect [org.hibernate.dialect.Dialect]
20190612-23:33:23: [INFO]: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4 [org.hibernate.engine.jdbc.JdbcSupportLoader]
20190612-23:33:23: [INFO]: Database ->
       name : H2
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Driver ->
       name : H2 JDBC Driver
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Using default transaction strategy (direct JDBC transactions) [org.hibernate.transaction.TransactionFactoryFactory]
20190612-23:33:23: [INFO]: No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended) [org.hibernate.transaction.TransactionManagerLookupFactory]
20190612-23:33:23: [INFO]: Automatic flush during beforeCompletion(): disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Automatic session close at end of transaction: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: JDBC batch size: 15 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: JDBC batch updates for versioned data: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Scrollable result sets: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: JDBC3 getGeneratedKeys(): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Connection release mode: auto [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Default batch fetch size: 1 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Generate SQL with comments: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Order SQL updates by primary key: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Order SQL inserts for batching: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Using ASTQueryTranslatorFactory [org.hibernate.hql.ast.ASTQueryTranslatorFactory]
20190612-23:33:23: [INFO]: Query language substitutions: {} [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: JPA-QL strict compliance: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Second-level cache: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Query cache: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Cache region factory : org.hibernate.cache.impl.NoCachingRegionFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Optimize cache for minimal puts: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Structured second-level cache entries: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Statistics: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Deleted entity synthetic identifier rollback: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Default entity-mode: pojo [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Named query checking : enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Check Nullability in Core (should be disabled when Bean Validation is on): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: building session factory [org.hibernate.impl.SessionFactoryImpl]
20190612-23:33:23: [INFO]: Type registration [wrapper_characters_clob] overrides previous : org.hibernate.type.CharacterArrayClobType@182e4365 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [wrapper_materialized_blob] overrides previous : org.hibernate.type.WrappedMaterializedBlobType@789dd6bf [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [materialized_clob] overrides previous : org.hibernate.type.MaterializedClobType@1e6d30c0 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [java.sql.Blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [materialized_blob] overrides previous : org.hibernate.type.MaterializedBlobType@69d667a5 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [java.sql.Clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [characters_clob] overrides previous : org.hibernate.type.PrimitiveCharacterArrayClobType@8f4b803 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Not binding factory to JNDI, no JNDI name configured [org.hibernate.impl.SessionFactoryObjectFactory]
20190612-23:33:23: [INFO]: Running hbm2ddl schema update [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:23: [INFO]: fetching database metadata [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:23: [INFO]: updating schema [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:23: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TASK_QUEUE [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: columns: [nextexecution, trycount, pid, id, status] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: indexes: [index_exec_task, primary_key_1, index_pid_task] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TRY_HISTORY [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: columns: [lastreplicationattemptdate, replicationattempts, pid, id, nodeid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: indexes: [primary_key_b, index_pid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: schema update complete [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:23: [INFO]: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@5d8e4fa8: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationTaskRepository,replicationAttemptHistoryRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
Results found with findByNodeId('urn:node:testNode'):
--------------------------------------------
ReplicationTryHistory [id=17, pid=foo_pid, nodeId=urn:node:testNode, replication attempts=4, last attempt=06/12/2019:23:33:23:144']
ReplicationTryHistory [id=18, pid=bar_pid, nodeId=urn:node:testNode, replication attempts=2, last attempt=06/12/2019:23:33:23:144']
20190612-23:33:23: [INFO]: Closing org.springframework.context.annotation.AnnotationConfigApplicationContext@11120583: startup date [Wed Jun 12 23:33:23 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:23: [INFO]: Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@5d8e4fa8: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationTaskRepository,replicationAttemptHistoryRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Closing JPA EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:23: [INFO]: closing [org.hibernate.impl.SessionFactoryImpl]
20190612-23:33:23: [INFO]: Refreshing org.springframework.context.annotation.AnnotationConfigApplicationContext@1b841e7d: startup date [Wed Jun 12 23:33:23 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:23: [INFO]: Overriding bean definition for bean 'replicationAttemptHistoryRepository': replacing [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Overriding bean definition for bean 'replicationTaskRepository': replacing [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Overriding bean definition for bean 'dataSource': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationPostgresRepositoryFactory; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationH2RepositoryFactory; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class org.dataone.cn.data.repository.ReplicationH2RepositoryFactory] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Overriding bean definition for bean 'jpaVendorAdapter': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationPostgresRepositoryFactory; factoryMethodName=jpaVendorAdapter; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationH2RepositoryFactory; factoryMethodName=jpaVendorAdapter; initMethodName=null; destroyMethodName=(inferred); defined in class org.dataone.cn.data.repository.ReplicationH2RepositoryFactory] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Creating embedded database 'testdb' [org.springframework.jdbc.datasource.embedded.EmbeddedDatabaseFactory]
20190612-23:33:23: [INFO]: Building JPA container EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:23: [INFO]: Processing PersistenceUnitInfo [
	name: default
	...] [org.hibernate.ejb.Ejb3Configuration]
20190612-23:33:23: [INFO]: Binding entity from annotated class: org.dataone.cn.data.repository.ReplicationAttemptHistory [org.hibernate.cfg.AnnotationBinder]
20190612-23:33:23: [INFO]: Bind entity org.dataone.cn.data.repository.ReplicationAttemptHistory on table replication_try_history [org.hibernate.cfg.annotations.EntityBinder]
20190612-23:33:23: [INFO]: Binding entity from annotated class: org.dataone.cn.data.repository.ReplicationTask [org.hibernate.cfg.AnnotationBinder]
20190612-23:33:23: [INFO]: Bind entity org.dataone.cn.data.repository.ReplicationTask on table replication_task_queue [org.hibernate.cfg.annotations.EntityBinder]
20190612-23:33:23: [INFO]: Hibernate Validator not found: ignoring [org.hibernate.cfg.Configuration]
20190612-23:33:23: [INFO]: Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled. [org.hibernate.cfg.search.HibernateSearchEventListenerRegister]
20190612-23:33:23: [INFO]: Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider [org.hibernate.connection.ConnectionProviderFactory]
20190612-23:33:23: [INFO]: Using provided datasource [org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider]
20190612-23:33:23: [INFO]: Using dialect: org.hibernate.dialect.H2Dialect [org.hibernate.dialect.Dialect]
20190612-23:33:23: [INFO]: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4 [org.hibernate.engine.jdbc.JdbcSupportLoader]
20190612-23:33:23: [INFO]: Database ->
       name : H2
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Driver ->
       name : H2 JDBC Driver
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Using default transaction strategy (direct JDBC transactions) [org.hibernate.transaction.TransactionFactoryFactory]
20190612-23:33:23: [INFO]: No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended) [org.hibernate.transaction.TransactionManagerLookupFactory]
20190612-23:33:23: [INFO]: Automatic flush during beforeCompletion(): disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Automatic session close at end of transaction: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: JDBC batch size: 15 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: JDBC batch updates for versioned data: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Scrollable result sets: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: JDBC3 getGeneratedKeys(): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Connection release mode: auto [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Default batch fetch size: 1 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Generate SQL with comments: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Order SQL updates by primary key: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Order SQL inserts for batching: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Using ASTQueryTranslatorFactory [org.hibernate.hql.ast.ASTQueryTranslatorFactory]
20190612-23:33:23: [INFO]: Query language substitutions: {} [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: JPA-QL strict compliance: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Second-level cache: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Query cache: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Cache region factory : org.hibernate.cache.impl.NoCachingRegionFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Optimize cache for minimal puts: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Structured second-level cache entries: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Statistics: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Deleted entity synthetic identifier rollback: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Default entity-mode: pojo [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Named query checking : enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Check Nullability in Core (should be disabled when Bean Validation is on): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: building session factory [org.hibernate.impl.SessionFactoryImpl]
20190612-23:33:23: [INFO]: Type registration [wrapper_characters_clob] overrides previous : org.hibernate.type.CharacterArrayClobType@182e4365 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [wrapper_materialized_blob] overrides previous : org.hibernate.type.WrappedMaterializedBlobType@789dd6bf [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [materialized_clob] overrides previous : org.hibernate.type.MaterializedClobType@1e6d30c0 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [java.sql.Blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [materialized_blob] overrides previous : org.hibernate.type.MaterializedBlobType@69d667a5 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [java.sql.Clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [characters_clob] overrides previous : org.hibernate.type.PrimitiveCharacterArrayClobType@8f4b803 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Not binding factory to JNDI, no JNDI name configured [org.hibernate.impl.SessionFactoryObjectFactory]
20190612-23:33:23: [INFO]: Running hbm2ddl schema update [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:23: [INFO]: fetching database metadata [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:23: [INFO]: updating schema [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:23: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TASK_QUEUE [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: columns: [nextexecution, trycount, pid, id, status] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: indexes: [index_exec_task, primary_key_1, index_pid_task] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TRY_HISTORY [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: columns: [lastreplicationattemptdate, replicationattempts, pid, id, nodeid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: indexes: [primary_key_b, index_pid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: schema update complete [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:23: [INFO]: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@eb695e8: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationAttemptHistoryRepository,replicationTaskRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
Results found with findByNodeId('urn:node:testNode'):
--------------------------------------------
ReplicationTryHistory [id=19, pid=foo_pid, nodeId=urn:node:testNode, replication attempts=4, last attempt=06/12/2019:23:33:23:197']
ReplicationTryHistory [id=20, pid=bar_pid, nodeId=urn:node:testNode, replication attempts=2, last attempt=06/12/2019:23:33:23:198']
20190612-23:33:23: [INFO]: Closing org.springframework.context.annotation.AnnotationConfigApplicationContext@1b841e7d: startup date [Wed Jun 12 23:33:23 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:23: [INFO]: Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@eb695e8: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationAttemptHistoryRepository,replicationTaskRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Closing JPA EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:23: [INFO]: closing [org.hibernate.impl.SessionFactoryImpl]
20190612-23:33:23: [INFO]: Refreshing org.springframework.context.annotation.AnnotationConfigApplicationContext@434d001d: startup date [Wed Jun 12 23:33:23 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:23: [INFO]: Overriding bean definition for bean 'replicationAttemptHistoryRepository': replacing [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Overriding bean definition for bean 'replicationTaskRepository': replacing [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.data.jpa.repository.support.JpaRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Overriding bean definition for bean 'dataSource': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationPostgresRepositoryFactory; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationH2RepositoryFactory; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class org.dataone.cn.data.repository.ReplicationH2RepositoryFactory] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Overriding bean definition for bean 'jpaVendorAdapter': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationPostgresRepositoryFactory; factoryMethodName=jpaVendorAdapter; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=replicationH2RepositoryFactory; factoryMethodName=jpaVendorAdapter; initMethodName=null; destroyMethodName=(inferred); defined in class org.dataone.cn.data.repository.ReplicationH2RepositoryFactory] [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Creating embedded database 'testdb' [org.springframework.jdbc.datasource.embedded.EmbeddedDatabaseFactory]
20190612-23:33:23: [INFO]: Building JPA container EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:23: [INFO]: Processing PersistenceUnitInfo [
	name: default
	...] [org.hibernate.ejb.Ejb3Configuration]
20190612-23:33:23: [INFO]: Binding entity from annotated class: org.dataone.cn.data.repository.ReplicationAttemptHistory [org.hibernate.cfg.AnnotationBinder]
20190612-23:33:23: [INFO]: Bind entity org.dataone.cn.data.repository.ReplicationAttemptHistory on table replication_try_history [org.hibernate.cfg.annotations.EntityBinder]
20190612-23:33:23: [INFO]: Binding entity from annotated class: org.dataone.cn.data.repository.ReplicationTask [org.hibernate.cfg.AnnotationBinder]
20190612-23:33:23: [INFO]: Bind entity org.dataone.cn.data.repository.ReplicationTask on table replication_task_queue [org.hibernate.cfg.annotations.EntityBinder]
20190612-23:33:23: [INFO]: Hibernate Validator not found: ignoring [org.hibernate.cfg.Configuration]
20190612-23:33:23: [INFO]: Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled. [org.hibernate.cfg.search.HibernateSearchEventListenerRegister]
20190612-23:33:23: [INFO]: Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider [org.hibernate.connection.ConnectionProviderFactory]
20190612-23:33:23: [INFO]: Using provided datasource [org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider]
20190612-23:33:23: [INFO]: Using dialect: org.hibernate.dialect.H2Dialect [org.hibernate.dialect.Dialect]
20190612-23:33:23: [INFO]: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4 [org.hibernate.engine.jdbc.JdbcSupportLoader]
20190612-23:33:23: [INFO]: Database ->
       name : H2
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Driver ->
       name : H2 JDBC Driver
    version : 1.3.163 (2011-12-30)
      major : 1
      minor : 3 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Using default transaction strategy (direct JDBC transactions) [org.hibernate.transaction.TransactionFactoryFactory]
20190612-23:33:23: [INFO]: No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended) [org.hibernate.transaction.TransactionManagerLookupFactory]
20190612-23:33:23: [INFO]: Automatic flush during beforeCompletion(): disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Automatic session close at end of transaction: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: JDBC batch size: 15 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: JDBC batch updates for versioned data: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Scrollable result sets: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: JDBC3 getGeneratedKeys(): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Connection release mode: auto [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Default batch fetch size: 1 [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Generate SQL with comments: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Order SQL updates by primary key: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Order SQL inserts for batching: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Using ASTQueryTranslatorFactory [org.hibernate.hql.ast.ASTQueryTranslatorFactory]
20190612-23:33:23: [INFO]: Query language substitutions: {} [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: JPA-QL strict compliance: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Second-level cache: enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Query cache: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Cache region factory : org.hibernate.cache.impl.NoCachingRegionFactory [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Optimize cache for minimal puts: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Structured second-level cache entries: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Statistics: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Deleted entity synthetic identifier rollback: disabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Default entity-mode: pojo [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Named query checking : enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: Check Nullability in Core (should be disabled when Bean Validation is on): enabled [org.hibernate.cfg.SettingsFactory]
20190612-23:33:23: [INFO]: building session factory [org.hibernate.impl.SessionFactoryImpl]
20190612-23:33:23: [INFO]: Type registration [wrapper_characters_clob] overrides previous : org.hibernate.type.CharacterArrayClobType@182e4365 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [wrapper_materialized_blob] overrides previous : org.hibernate.type.WrappedMaterializedBlobType@789dd6bf [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [materialized_clob] overrides previous : org.hibernate.type.MaterializedClobType@1e6d30c0 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [java.sql.Blob] overrides previous : org.hibernate.type.BlobType@38929da [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [materialized_blob] overrides previous : org.hibernate.type.MaterializedBlobType@69d667a5 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [java.sql.Clob] overrides previous : org.hibernate.type.ClobType@7fe87c0e [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Type registration [characters_clob] overrides previous : org.hibernate.type.PrimitiveCharacterArrayClobType@8f4b803 [org.hibernate.type.BasicTypeRegistry]
20190612-23:33:23: [INFO]: Not binding factory to JNDI, no JNDI name configured [org.hibernate.impl.SessionFactoryObjectFactory]
20190612-23:33:23: [INFO]: Running hbm2ddl schema update [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:23: [INFO]: fetching database metadata [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:23: [INFO]: updating schema [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:23: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TASK_QUEUE [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: columns: [nextexecution, trycount, pid, id, status] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: indexes: [index_exec_task, primary_key_1, index_pid_task] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: table found: TESTDB.PUBLIC.REPLICATION_TRY_HISTORY [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: columns: [lastreplicationattemptdate, replicationattempts, pid, id, nodeid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: foreign keys: [] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: indexes: [primary_key_b, index_pid] [org.hibernate.tool.hbm2ddl.TableMetadata]
20190612-23:33:23: [INFO]: schema update complete [org.hibernate.tool.hbm2ddl.SchemaUpdate]
20190612-23:33:23: [INFO]: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@4db16677: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationAttemptHistoryRepository,replicationTaskRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
Results found with findByPid('foo_pid'):
--------------------------------------------
ReplicationTryHistory [id=21, pid=foo_pid, nodeId=urn:node:testNode, replication attempts=4, last attempt=06/12/2019:23:33:23:250']
20190612-23:33:23: [INFO]: Closing org.springframework.context.annotation.AnnotationConfigApplicationContext@434d001d: startup date [Wed Jun 12 23:33:23 UTC 2019]; root of context hierarchy [org.springframework.context.annotation.AnnotationConfigApplicationContext]
20190612-23:33:23: [INFO]: Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@4db16677: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,replicationH2RepositoryFactory,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor,replicationPostgresRepositoryFactory,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,replicationAttemptHistoryRepository,replicationTaskRepository,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#1,dataSource,jpaVendorAdapter,entityManagerFactory,transactionManager]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]
20190612-23:33:23: [INFO]: Closing JPA EntityManagerFactory for persistence unit 'default' [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean]
20190612-23:33:23: [INFO]: closing [org.hibernate.impl.SessionFactoryImpl]
Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.588 sec
20190612-23:33:23: [INFO]: Closing org.springframework.context.support.GenericApplicationContext@542f6803: startup date [Wed Jun 12 23:32:43 UTC 2019]; root of context hierarchy [org.springframework.context.support.GenericApplicationContext]
20190612-23:33:23: [INFO]: Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@7b1e5e55: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,mylog,log4jInitialization,readSystemMetadataResource,nodeListResource,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor]; root of factory hierarchy [org.springframework.beans.factory.support.DefaultListableBeanFactory]

Results :

Tests run: 15, Failures: 0, Errors: 0, Skipped: 0

[JENKINS] Recording test results
[WARNING] Attempt to (de-)serialize anonymous class hudson.maven.reporters.SurefireArchiver$2; see: https://jenkins.io/redirect/serialization-of-anonymous-classes/
[WARNING] Attempt to (de-)serialize anonymous class hudson.maven.reporters.BuildInfoRecorder$1; see: https://jenkins.io/redirect/serialization-of-anonymous-classes/
[INFO] 
[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ d1_replication ---
[INFO] Building jar: /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/d1_replication-2.4.0-SNAPSHOT.jar
[INFO] 
[INFO] --- maven-install-plugin:2.4:install (default-install) @ d1_replication ---
[INFO] Installing /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/d1_replication-2.4.0-SNAPSHOT.jar to /var/lib/jenkins/.m2/repository/org/dataone/d1_replication/2.4.0-SNAPSHOT/d1_replication-2.4.0-SNAPSHOT.jar
[INFO] Installing /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/pom.xml to /var/lib/jenkins/.m2/repository/org/dataone/d1_replication/2.4.0-SNAPSHOT/d1_replication-2.4.0-SNAPSHOT.pom
[INFO] 
[INFO] >>> maven-javadoc-plugin:2.10.4:javadoc (default-cli) > generate-sources @ d1_replication >>>
[INFO] 
[INFO] --- buildnumber-maven-plugin:1.4:create (default) @ d1_replication ---
[INFO] Executing: /bin/sh -c cd '/var/lib/jenkins/jobs/d1_replication/workspace/d1_replication' && 'svn' '--non-interactive' 'info'
[INFO] Working directory: /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication
[INFO] Storing buildNumber: null at timestamp: 1560382405830
[INFO] Executing: /bin/sh -c cd '/var/lib/jenkins/jobs/d1_replication/workspace/d1_replication' && 'svn' '--non-interactive' 'info'
[INFO] Working directory: /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication
[INFO] Storing buildScmBranch: UNKNOWN_BRANCH
[WARNING] Failed to getClass for org.apache.maven.plugin.javadoc.JavadocReport
[INFO] 
[INFO] <<< maven-javadoc-plugin:2.10.4:javadoc (default-cli) < generate-sources @ d1_replication <<<
[INFO] 
[INFO] 
[INFO] --- maven-javadoc-plugin:2.10.4:javadoc (default-cli) @ d1_replication ---
[INFO] 
Loading source files for package org.dataone.cn.data.repository...
Loading source files for package org.dataone.service.cn.replication.v2...
Loading source files for package org.dataone.service.cn.replication...
Loading source files for package org.dataone.service.cn.replication.v1...
Constructing Javadoc information...
Standard Doclet version 1.8.0_212
Building tree for all the packages and classes...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/cn/data/repository/ReplicationAttemptHistory.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/cn/data/repository/ReplicationAttemptHistoryRepository.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/cn/data/repository/ReplicationPostgresRepositoryFactory.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/cn/data/repository/ReplicationTask.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/cn/data/repository/ReplicationTaskRepository.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/v2/MNCommunication.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/ApiVersion.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/MNReplicationTask.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/QueuedReplicationAuditor.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/RejectedReplicationTaskHandler.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/ReplicationCommunication.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/ReplicationEventListener.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/ReplicationFactory.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/ReplicationManager.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/ReplicationPrioritizationStrategy.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/ReplicationRepositoryFactory.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/ReplicationService.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/ReplicationStatusMonitor.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/ReplicationTaskMonitor.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/ReplicationTaskProcessor.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/ReplicationTaskQueue.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/StaleReplicationRequestAuditor.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/v1/MNCommunication.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/overview-frame.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/cn/data/repository/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/cn/data/repository/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/cn/data/repository/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/v1/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/v1/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/v1/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/v2/package-frame.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/v2/package-summary.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/v2/package-tree.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/constant-values.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/serialized-form.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/cn/data/repository/class-use/ReplicationAttemptHistory.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/cn/data/repository/class-use/ReplicationTask.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/cn/data/repository/class-use/ReplicationTaskRepository.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/cn/data/repository/class-use/ReplicationPostgresRepositoryFactory.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/cn/data/repository/class-use/ReplicationAttemptHistoryRepository.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/v2/class-use/MNCommunication.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/class-use/ReplicationFactory.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/class-use/ReplicationStatusMonitor.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/class-use/ReplicationEventListener.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/class-use/MNReplicationTask.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/class-use/ApiVersion.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/class-use/ReplicationRepositoryFactory.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/class-use/RejectedReplicationTaskHandler.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/class-use/ReplicationTaskProcessor.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/class-use/ReplicationTaskQueue.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/class-use/ReplicationTaskMonitor.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/class-use/ReplicationCommunication.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/class-use/ReplicationManager.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/class-use/StaleReplicationRequestAuditor.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/class-use/ReplicationService.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/class-use/ReplicationPrioritizationStrategy.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/class-use/QueuedReplicationAuditor.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/v1/class-use/MNCommunication.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/cn/data/repository/package-use.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/package-use.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/v1/package-use.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/org/dataone/service/cn/replication/v2/package-use.html...
Building index for all the packages and classes...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/overview-tree.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/index-all.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/deprecated-list.html...
Building index for all classes...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/allclasses-frame.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/allclasses-noframe.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/index.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/overview-summary.html...
Generating /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/site/apidocs/help-doc.html...
12 warnings
[WARNING] Javadoc Warnings
[WARNING] /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/src/main/java/org/dataone/service/cn/replication/ReplicationManager.java:258: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/src/main/java/org/dataone/service/cn/replication/ReplicationManager.java:280: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/src/main/java/org/dataone/service/cn/replication/ReplicationManager.java:303: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/src/main/java/org/dataone/service/cn/replication/ReplicationManager.java:499: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/src/main/java/org/dataone/service/cn/replication/ReplicationManager.java:542: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/src/main/java/org/dataone/service/cn/replication/ReplicationManager.java:572: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/src/main/java/org/dataone/service/cn/replication/ReplicationManager.java:622: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/src/main/java/org/dataone/service/cn/replication/ReplicationManager.java:146: warning - @param argument "repAttemptHistoryRepos" is not a parameter name.
[WARNING] /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/src/main/java/org/dataone/service/cn/replication/ReplicationService.java:140: warning - @param argument "serialVersion" is not a parameter name.
[WARNING] /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/src/main/java/org/dataone/service/cn/replication/ReplicationService.java:331: warning - @param argument "session" is not a parameter name.
[WARNING] /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/src/main/java/org/dataone/service/cn/replication/ReplicationTaskQueue.java:82: warning - @return tag has no arguments.
[WARNING] /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/src/main/java/org/dataone/service/cn/replication/ReplicationTaskQueue.java:99: warning - @return tag has no arguments.
[JENKINS] Archiving  javadoc
Notifying upstream projects of job completion
Join notifier requires a CauseAction
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time:  53.338 s
[INFO] Finished at: 2019-06-12T23:33:28Z
[INFO] ------------------------------------------------------------------------
Waiting for Jenkins to finish collecting data
[JENKINS] Archiving /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/pom.xml to org.dataone/d1_replication/2.4.0-SNAPSHOT/d1_replication-2.4.0-SNAPSHOT.pom
[JENKINS] Archiving /var/lib/jenkins/jobs/d1_replication/workspace/d1_replication/target/d1_replication-2.4.0-SNAPSHOT.jar to org.dataone/d1_replication/2.4.0-SNAPSHOT/d1_replication-2.4.0-SNAPSHOT.jar
channel stopped
Maven RedeployPublisher use remote  maven settings from : /usr/share/maven/conf/settings.xml
[ERROR] uniqueVersion == false is not anymore supported in maven 3
[INFO] Deployment in file:///var/www/maven (id=,uniqueVersion=false)
Deploying the main artifact d1_replication-2.4.0-SNAPSHOT.jar
Downloading: file:///var/www/maven/org/dataone/d1_replication/2.4.0-SNAPSHOT/maven-metadata.xml
Downloaded: file:///var/www/maven/org/dataone/d1_replication/2.4.0-SNAPSHOT/maven-metadata.xml (775 B at 756.8 KB/sec)
Uploading: file:///var/www/maven/org/dataone/d1_replication/2.4.0-SNAPSHOT/d1_replication-2.4.0-20190612.233332-4.jar
Uploaded: file:///var/www/maven/org/dataone/d1_replication/2.4.0-SNAPSHOT/d1_replication-2.4.0-20190612.233332-4.jar (74 KB at 73502.9 KB/sec)
Uploading: file:///var/www/maven/org/dataone/d1_replication/2.4.0-SNAPSHOT/d1_replication-2.4.0-20190612.233332-4.pom
Uploaded: file:///var/www/maven/org/dataone/d1_replication/2.4.0-SNAPSHOT/d1_replication-2.4.0-20190612.233332-4.pom (8 KB at 7042.0 KB/sec)
Downloading: file:///var/www/maven/org/dataone/d1_replication/maven-metadata.xml
Downloaded: file:///var/www/maven/org/dataone/d1_replication/maven-metadata.xml (2 KB at 1449.2 KB/sec)
Uploading: file:///var/www/maven/org/dataone/d1_replication/2.4.0-SNAPSHOT/maven-metadata.xml
Uploaded: file:///var/www/maven/org/dataone/d1_replication/2.4.0-SNAPSHOT/maven-metadata.xml (775 B)
Uploading: file:///var/www/maven/org/dataone/d1_replication/maven-metadata.xml
Uploaded: file:///var/www/maven/org/dataone/d1_replication/maven-metadata.xml (2 KB)
[INFO] Deployment done in 44 ms
Notifying upstream projects of job completion
Not triggering d1_process_daemon because it has dependencies in the downstream project list
Not triggering d1_replication_auditor because it has dependencies in the downstream project list
Finished: SUCCESS