Started calculate disk usage of build Finished Calculation of disk usage of build in 0 seconds Started calculate disk usage of workspace Finished Calculation of disk usage of workspace in 0 seconds [INFO] [INFO] ---------------< it.tidalwave.solidblue3:solidblue3-dao >--------------- [INFO] Building SolidBlue III :: Dao 1.1-SNAPSHOT [5/6] [INFO] from modules/Dao/pom.xml [INFO] --------------------------------[ jar ]--------------------------------- [INFO] [INFO] --- clean:3.2.0:clean (default-clean) @ solidblue3-dao --- [INFO] [INFO] --- buildnumber:3.0.0:create (default) @ solidblue3-dao --- [INFO] ShortRevision tag detected. The value is '12'. [INFO] Executing: /bin/sh -c cd '/var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao' && 'git' 'rev-parse' '--verify' '--short=12' 'HEAD' [INFO] Working directory: /var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao [INFO] Storing buildNumber: 6332a4670def at timestamp: 1682804865755 [WARNING] Cannot get the branch information from the git repository: Detecting the current branch failed: fatal: ref HEAD is not a symbolic ref [INFO] ShortRevision tag detected. The value is '12'. [INFO] Executing: /bin/sh -c cd '/var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao' && 'git' 'rev-parse' '--verify' '--short=12' 'HEAD' [INFO] Working directory: /var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao [INFO] Storing buildScmBranch: UNKNOWN [INFO] [INFO] --- enforcer:3.3.0:enforce (enforce-maven) @ solidblue3-dao --- [INFO] Rule 0: org.apache.maven.enforcer.rules.dependency.DependencyConvergence passed [INFO] Rule 3: org.apache.maven.enforcer.rules.RequirePluginVersions passed [INFO] [INFO] --- enforcer:3.3.0:enforce (enforce-banned-dependencies) @ solidblue3-dao --- [INFO] Rule 0: org.apache.maven.enforcer.rules.dependency.BannedDependencies passed [INFO] [INFO] --- antrun:3.1.0:run (generate-and-print-build-description) @ solidblue3-dao --- [INFO] Executing tasks [INFO] [echo] DESCRIPTION: origin/master 1.1-SNAPSHOT 6332a4670def [INFO] [echo] Java Version: 17.0.6 -source 17 -target 17 -release 17 [INFO] Executed tasks [INFO] [INFO] --- jacoco:0.8.9:prepare-agent (prepare-agent) @ solidblue3-dao --- [INFO] tft.test.jacoco.argLine set to -javaagent:/var/jenkins_home/jobs/SolidBlue3_Metrics/workspace@tmp/repository/org/jacoco/org.jacoco.agent/0.8.9/org.jacoco.agent-0.8.9-runtime.jar=destfile=/var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao/target/jacoco.exec [INFO] [INFO] --- resources:3.3.1:resources (default-resources) @ solidblue3-dao --- [INFO] Copying 2 resources from src/main/resources to target/classes [INFO] [INFO] --- compiler:3.11.0:compile (default-compile) @ solidblue3-dao --- [INFO] Changes detected - recompiling the module! :dependency [INFO] Compiling 4 source files with javac [forked debug deprecation release 17] to target/classes [WARNING] No processor claimed any of these annotations: /jakarta.persistence.OrderBy,/jakarta.persistence.Column,/jakarta.persistence.OneToMany,/jakarta.persistence.Table,/jakarta.persistence.Entity,/org.springframework.stereotype.Component,/jakarta.annotation.Nonnull,/org.hibernate.annotations.Cascade,/jakarta.transaction.Transactional,/edu.umd.cs.findbugs.annotations.SuppressFBWarnings,/jakarta.persistence.Id [INFO] [INFO] --- resources:3.3.1:testResources (default-testResources) @ solidblue3-dao --- [INFO] Copying 2 resources from src/test/resources to target/test-classes [INFO] [INFO] --- compiler:3.11.0:testCompile (default-testCompile) @ solidblue3-dao --- [INFO] Changes detected - recompiling the module! :dependency [INFO] Compiling 5 source files with javac [forked debug deprecation release 17] to target/test-classes [INFO] [INFO] --- surefire:3.0.0:test (default-test) @ solidblue3-dao --- [INFO] Using auto detected provider org.apache.maven.surefire.testng.TestNGProvider [INFO] [INFO] ------------------------------------------------------- [INFO] T E S T S [INFO] ------------------------------------------------------- [INFO] Running TestSuite 21:47:54.077 [main ] INFO o.s.t.c.support.AnnotationConfigContextLoaderUtils - Could not detect default configuration classes for test class [it.tidalwave.datamanager.dao.impl.jpa.FingerprintEntityTest]: FingerprintEntityTest does not declare any static, non-private, non-final, nested classes annotated with @Configuration. 21:47:54.465 [main ] INFO o.s.b.t.context.SpringBootTestContextBootstrapper - Found @SpringBootConfiguration it.tidalwave.datamanager.dao.impl.jpa.TestConfiguration for test class it.tidalwave.datamanager.dao.impl.jpa.FingerprintEntityTest 21:47:54.606 [main ] INFO o.s.t.c.support.AnnotationConfigContextLoaderUtils - Could not detect default configuration classes for test class [it.tidalwave.datamanager.dao.impl.jpa.JpaDataManagerDaoTest]: JpaDataManagerDaoTest does not declare any static, non-private, non-final, nested classes annotated with @Configuration. 21:47:54.614 [main ] INFO o.s.b.t.context.SpringBootTestContextBootstrapper - Found @SpringBootConfiguration it.tidalwave.datamanager.dao.impl.jpa.TestConfiguration for test class it.tidalwave.datamanager.dao.impl.jpa.JpaDataManagerDaoTest 21:47:54.631 [main ] INFO o.s.t.c.support.AnnotationConfigContextLoaderUtils - Could not detect default configuration classes for test class [it.tidalwave.datamanager.dao.impl.jpa.ManagedFileEntityTest]: ManagedFileEntityTest does not declare any static, non-private, non-final, nested classes annotated with @Configuration. 21:47:54.643 [main ] INFO o.s.b.t.context.SpringBootTestContextBootstrapper - Found @SpringBootConfiguration it.tidalwave.datamanager.dao.impl.jpa.TestConfiguration for test class it.tidalwave.datamanager.dao.impl.jpa.ManagedFileEntityTest 21:47:54.702 [main ] INFO i.t.datamanager.dao.impl.jpa.FingerprintEntityTest - STARTING TESTS OF it.tidalwave.datamanager.dao.impl.jpa.FingerprintEntityTest . ____ _ __ _ _ /\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \ ( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \ \\/ ___)| |_)| | | | | || (_| | ) ) ) ) ' |____| .__|_| |_|_| |_\__, | / / / / =========|_|==============|___/=/_/_/_/ :: Spring Boot :: (v3.0.5) 21:47:55.897 [main ] INFO i.t.datamanager.dao.impl.jpa.FingerprintEntityTest - Starting FingerprintEntityTest using Java 17.0.6 with PID 669022 (/var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao/target/test-classes started by jenkins in /var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao) 21:47:55.898 [main ] DEBUG i.t.datamanager.dao.impl.jpa.FingerprintEntityTest - Running with Spring Boot v3.0.5, Spring v6.0.7 21:47:55.898 [main ] INFO i.t.datamanager.dao.impl.jpa.FingerprintEntityTest - The following 1 profile is active: "test" 21:47:55.926 [main ] TRACE o.s.boot.context.config.ConfigDataLoaders - Loading file [.] using loader org.springframework.boot.context.config.StandardConfigDataLoader 21:47:55.926 [main ] TRACE o.s.boot.context.config.ConfigDataLoaders - Loading class path resource [config/module-test.yml] using loader org.springframework.boot.context.config.StandardConfigDataLoader 21:47:55.926 [main ] TRACE o.s.boot.context.config.ConfigDataLoaders - Loading class path resource [config/module.yml] using loader org.springframework.boot.context.config.StandardConfigDataLoader 21:47:56.686 [main ] INFO o.s.d.r.config.RepositoryConfigurationDelegate - Bootstrapping Spring Data JPA repositories in DEFAULT mode. 21:47:56.799 [main ] INFO o.s.d.r.config.RepositoryConfigurationDelegate - Finished Spring Data repository scanning in 96 ms. Found 1 JPA repository interfaces. 21:47:57.407 [main ] DEBUG com.zaxxer.hikari.HikariConfig - Driver class com.p6spy.engine.spy.P6SpyDriver found in Thread context class loader jdk.internal.loader.ClassLoaders$AppClassLoader@251a69d7 21:47:57.578 [main ] DEBUG org.jboss.logging - Logging Provider: org.jboss.logging.Slf4jLoggerProvider found via system property 21:47:57.724 [main ] INFO org.hibernate.jpa.internal.util.LogHelper - HHH000204: Processing PersistenceUnitInfo [name: default] 21:47:57.970 [main ] INFO org.hibernate.Version - HHH000412: Hibernate ORM core version 6.1.7.Final 21:47:58.626 [main ] DEBUG com.zaxxer.hikari.HikariConfig - HikariPool-1 - configuration: 21:47:58.629 [main ] DEBUG com.zaxxer.hikari.HikariConfig - allowPoolSuspension.............false 21:47:58.630 [main ] DEBUG com.zaxxer.hikari.HikariConfig - autoCommit......................true 21:47:58.630 [main ] DEBUG com.zaxxer.hikari.HikariConfig - catalog.........................none 21:47:58.630 [main ] DEBUG com.zaxxer.hikari.HikariConfig - connectionInitSql...............none 21:47:58.631 [main ] DEBUG com.zaxxer.hikari.HikariConfig - connectionTestQuery.............none 21:47:58.631 [main ] DEBUG com.zaxxer.hikari.HikariConfig - connectionTimeout...............30000 21:47:58.631 [main ] DEBUG com.zaxxer.hikari.HikariConfig - dataSource......................none 21:47:58.631 [main ] DEBUG com.zaxxer.hikari.HikariConfig - dataSourceClassName.............none 21:47:58.631 [main ] DEBUG com.zaxxer.hikari.HikariConfig - dataSourceJNDI..................none 21:47:58.632 [main ] DEBUG com.zaxxer.hikari.HikariConfig - dataSourceProperties............{password=} 21:47:58.632 [main ] DEBUG com.zaxxer.hikari.HikariConfig - driverClassName................."com.p6spy.engine.spy.P6SpyDriver" 21:47:58.632 [main ] DEBUG com.zaxxer.hikari.HikariConfig - exceptionOverrideClassName......none 21:47:58.633 [main ] DEBUG com.zaxxer.hikari.HikariConfig - healthCheckProperties...........{} 21:47:58.633 [main ] DEBUG com.zaxxer.hikari.HikariConfig - healthCheckRegistry.............none 21:47:58.633 [main ] DEBUG com.zaxxer.hikari.HikariConfig - idleTimeout.....................600000 21:47:58.633 [main ] DEBUG com.zaxxer.hikari.HikariConfig - initializationFailTimeout.......1 21:47:58.633 [main ] DEBUG com.zaxxer.hikari.HikariConfig - isolateInternalQueries..........false 21:47:58.634 [main ] DEBUG com.zaxxer.hikari.HikariConfig - jdbcUrl.........................jdbc:p6spy:sqlite:target/test1.db 21:47:58.634 [main ] DEBUG com.zaxxer.hikari.HikariConfig - keepaliveTime...................0 21:47:58.634 [main ] DEBUG com.zaxxer.hikari.HikariConfig - leakDetectionThreshold..........0 21:47:58.634 [main ] DEBUG com.zaxxer.hikari.HikariConfig - maxLifetime.....................1800000 21:47:58.634 [main ] DEBUG com.zaxxer.hikari.HikariConfig - maximumPoolSize.................10 21:47:58.635 [main ] DEBUG com.zaxxer.hikari.HikariConfig - metricRegistry..................none 21:47:58.635 [main ] DEBUG com.zaxxer.hikari.HikariConfig - metricsTrackerFactory...........none 21:47:58.635 [main ] DEBUG com.zaxxer.hikari.HikariConfig - minimumIdle.....................10 21:47:58.635 [main ] DEBUG com.zaxxer.hikari.HikariConfig - password........................ 21:47:58.635 [main ] DEBUG com.zaxxer.hikari.HikariConfig - poolName........................"HikariPool-1" 21:47:58.636 [main ] DEBUG com.zaxxer.hikari.HikariConfig - readOnly........................false 21:47:58.636 [main ] DEBUG com.zaxxer.hikari.HikariConfig - registerMbeans..................false 21:47:58.636 [main ] DEBUG com.zaxxer.hikari.HikariConfig - scheduledExecutor...............none 21:47:58.637 [main ] DEBUG com.zaxxer.hikari.HikariConfig - schema..........................none 21:47:58.637 [main ] DEBUG com.zaxxer.hikari.HikariConfig - threadFactory...................internal 21:47:58.637 [main ] DEBUG com.zaxxer.hikari.HikariConfig - transactionIsolation............default 21:47:58.637 [main ] DEBUG com.zaxxer.hikari.HikariConfig - username........................none 21:47:58.638 [main ] DEBUG com.zaxxer.hikari.HikariConfig - validationTimeout...............5000 21:47:58.638 [main ] INFO com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Starting... 21:47:59.031 [main ] INFO com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@2f19ab6 21:47:59.035 [main ] INFO com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Start completed. 21:47:59.107 [main ] INFO SQL dialect - HHH000400: Using dialect: org.hibernate.community.dialect.SQLiteDialect 21:47:59.135 [riPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Pool stats (total=1, active=1, idle=0, waiting=0) 21:47:59.141 [l-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@52497a92 21:47:59.156 [l-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@5c8e8eab 21:47:59.168 [l-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@58b111e2 21:47:59.179 [l-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@1db38490 21:47:59.190 [l-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@437e05e 21:47:59.202 [l-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@2c64dda5 21:47:59.213 [l-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@7c0ae7ca 21:47:59.224 [l-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@7d9c7bdf 21:47:59.235 [l-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@46925122 21:47:59.246 [l-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After adding stats (total=10, active=0, idle=10, waiting=0) Hibernate: drop table if exists files 21:48:00.785 [main ] INFO p6spy - SQL: 1 nanos - drop table if exists files Hibernate: drop table if exists fingerprints 21:48:00.787 [main ] INFO p6spy - SQL: 0 nanos - drop table if exists fingerprints Hibernate: create table files (id varchar(36) not null, path text not null, primary key (id)) 21:48:00.853 [main ] INFO p6spy - SQL: 44 nanos - create table files (id varchar(36) not null, path text not null, primary key (id)) Hibernate: create table fingerprints (id varchar(36) not null, algorithm varchar(16) not null, file_id varchar(36) not null, name text not null, timestamp timestamp not null, fingerprint varchar(32) not null, primary key (id)) 21:48:00.914 [main ] INFO p6spy - SQL: 58 nanos - create table fingerprints (id varchar(36) not null, algorithm varchar(16) not null, file_id varchar(36) not null, name text not null, timestamp timestamp not null, fingerprint varchar(32) not null, primary key (id)) 21:48:00.922 [main ] INFO o.h.e.t.jta.platform.internal.JtaPlatformInitiator - HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform] 21:48:00.943 [main ] INFO o.s.orm.jpa.LocalContainerEntityManagerFactoryBean - Initialized JPA EntityManagerFactory for persistence unit 'default' 21:48:01.533 [main ] INFO i.t.datamanager.dao.impl.jpa.FingerprintEntityTest - Started FingerprintEntityTest in 6.655 seconds (process running for 9.763) 21:48:01.570 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Creating new transaction with name [it.tidalwave.datamanager.dao.impl.jpa.FingerprintEntityTest.test_equals_and_hashcode]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:01.571 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: BEGIN - tx definition: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:01.572 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Opened new EntityManager [SessionImpl(1103244633)] for JPA transaction 21:48:01.579 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@720907c9] 21:48:02.007 [main ] INFO i.t.datamanager.dao.impl.jpa.FingerprintEntityTest - ******************************* 21:48:02.007 [main ] INFO i.t.datamanager.dao.impl.jpa.FingerprintEntityTest - TEST "test equals and hashcode" 21:48:02.008 [main ] INFO i.t.datamanager.dao.impl.jpa.FingerprintEntityTest - ******************************* Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:02.105 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('', '', '', '2023-04-29T21:48:02.015+0000', '', 'id') 21:48:02.168 [main ] INFO p6spy - SQL: 58 nanos - Hibernate: select f1_0.id,f1_0.algorithm,f1_0.file_id,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.id=? 21:48:02.263 [main ] INFO p6spy - SQL: 7 nanos - select f1_0.id,f1_0.algorithm,f1_0.file_id,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.id='id' 21:48:02.287 [main ] INFO p6spy - SQL: 0 nanos - Hibernate: select f1_0.id,f1_0.algorithm,f1_0.file_id,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.id=? 21:48:02.293 [main ] INFO p6spy - SQL: 0 nanos - select f1_0.id,f1_0.algorithm,f1_0.file_id,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.id='id' 21:48:02.295 [main ] INFO p6spy - SQL: 0 nanos - Hibernate: select f1_0.id,f1_0.algorithm,f1_0.file_id,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.id=? 21:48:02.303 [main ] INFO p6spy - SQL: 0 nanos - select f1_0.id,f1_0.algorithm,f1_0.file_id,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.id='id' Hibernate: update fingerprints set algorithm=?, file_id=?, name=?, timestamp=?, fingerprint=? where id=? 21:48:02.306 [main ] INFO p6spy - SQL: 0 nanos - update fingerprints set algorithm='', file_id='', name='', timestamp='2023-04-29T21:48:02.015+0000', fingerprint='' where id='id' 21:48:02.309 [main ] INFO p6spy - SQL: 0 nanos - Hibernate: select f1_0.id,f1_0.algorithm,f1_0.file_id,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.id=? 21:48:02.311 [main ] INFO p6spy - SQL: 0 nanos - select f1_0.id,f1_0.algorithm,f1_0.file_id,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.id='id' Hibernate: update fingerprints set algorithm=?, file_id=?, name=?, timestamp=?, fingerprint=? where id=? 21:48:02.313 [main ] INFO p6spy - SQL: 0 nanos - update fingerprints set algorithm='', file_id='', name='', timestamp='2023-04-29T21:48:02.015+0000', fingerprint='' where id='id' 21:48:02.313 [main ] INFO p6spy - SQL: 0 nanos - Hibernate: select f1_0.id,f1_0.algorithm,f1_0.file_id,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.id=? 21:48:02.316 [main ] INFO p6spy - SQL: 0 nanos - select f1_0.id,f1_0.algorithm,f1_0.file_id,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.id='id' 21:48:02.317 [main ] INFO p6spy - SQL: 0 nanos - Hibernate: select f1_0.id,f1_0.algorithm,f1_0.file_id,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.id=? 21:48:02.319 [main ] INFO p6spy - SQL: 0 nanos - select f1_0.id,f1_0.algorithm,f1_0.file_id,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.id='id' 21:48:02.321 [main ] INFO p6spy - SQL: 0 nanos - Hibernate: select f1_0.id,f1_0.algorithm,f1_0.file_id,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.id=? 21:48:02.325 [main ] INFO p6spy - SQL: 0 nanos - select f1_0.id,f1_0.algorithm,f1_0.file_id,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.id='id' Hibernate: delete from fingerprints where id=? 21:48:02.328 [main ] INFO p6spy - SQL: 0 nanos - delete from fingerprints where id='id' 21:48:02.387 [main ] INFO p6spy - SQL: 54 nanos - 21:48:02.396 [main ] INFO i.t.datamanager.dao.impl.jpa.FingerprintEntityTest - TEST PASSED in 385 msec 21:48:02.397 [main ] INFO i.t.datamanager.dao.impl.jpa.FingerprintEntityTest - 21:48:02.407 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Initiating transaction rollback 21:48:02.407 [main ] WARN i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: ROLLBACK 21:48:02.407 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Rolling back JPA transaction on EntityManager [SessionImpl(1103244633)] 21:48:02.409 [main ] INFO p6spy - SQL: 0 nanos - 21:48:02.412 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Closing JPA EntityManager [SessionImpl(1103244633)] after transaction . ____ _ __ _ _ /\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \ ( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \ \\/ ___)| |_)| | | | | || (_| | ) ) ) ) ' |____| .__|_| |_|_| |_\__, | / / / / =========|_|==============|___/=/_/_/_/ :: Spring Boot :: (v3.0.5) 21:48:02.518 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - Starting JpaDataManagerDaoTest using Java 17.0.6 with PID 669022 (/var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao/target/test-classes started by jenkins in /var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao) 21:48:02.519 [main ] DEBUG i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - Running with Spring Boot v3.0.5, Spring v6.0.7 21:48:02.519 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - No active profile set, falling back to 1 default profile: "default" 21:48:02.523 [main ] TRACE o.s.boot.context.config.ConfigDataLoaders - Loading file [.] using loader org.springframework.boot.context.config.StandardConfigDataLoader 21:48:02.523 [main ] TRACE o.s.boot.context.config.ConfigDataLoaders - Loading class path resource [config/module-test.yml] using loader org.springframework.boot.context.config.StandardConfigDataLoader 21:48:02.523 [main ] TRACE o.s.boot.context.config.ConfigDataLoaders - Loading class path resource [config/module.yml] using loader org.springframework.boot.context.config.StandardConfigDataLoader 21:48:02.636 [main ] INFO o.s.d.r.config.RepositoryConfigurationDelegate - Bootstrapping Spring Data JPA repositories in DEFAULT mode. 21:48:02.653 [main ] INFO o.s.d.r.config.RepositoryConfigurationDelegate - Finished Spring Data repository scanning in 16 ms. Found 1 JPA repository interfaces. 21:48:02.735 [main ] DEBUG com.zaxxer.hikari.HikariConfig - Driver class com.p6spy.engine.spy.P6SpyDriver found in Thread context class loader jdk.internal.loader.ClassLoaders$AppClassLoader@251a69d7 21:48:02.805 [main ] INFO org.hibernate.jpa.internal.util.LogHelper - HHH000204: Processing PersistenceUnitInfo [name: default] 21:48:02.820 [main ] DEBUG com.zaxxer.hikari.HikariConfig - HikariPool-2 - configuration: 21:48:02.821 [main ] DEBUG com.zaxxer.hikari.HikariConfig - allowPoolSuspension.............false 21:48:02.822 [main ] DEBUG com.zaxxer.hikari.HikariConfig - autoCommit......................true 21:48:02.822 [main ] DEBUG com.zaxxer.hikari.HikariConfig - catalog.........................none 21:48:02.822 [main ] DEBUG com.zaxxer.hikari.HikariConfig - connectionInitSql...............none 21:48:02.823 [main ] DEBUG com.zaxxer.hikari.HikariConfig - connectionTestQuery.............none 21:48:02.823 [main ] DEBUG com.zaxxer.hikari.HikariConfig - connectionTimeout...............30000 21:48:02.823 [main ] DEBUG com.zaxxer.hikari.HikariConfig - dataSource......................none 21:48:02.828 [main ] DEBUG com.zaxxer.hikari.HikariConfig - dataSourceClassName.............none 21:48:02.828 [main ] DEBUG com.zaxxer.hikari.HikariConfig - dataSourceJNDI..................none 21:48:02.829 [main ] DEBUG com.zaxxer.hikari.HikariConfig - dataSourceProperties............{password=} 21:48:02.829 [main ] DEBUG com.zaxxer.hikari.HikariConfig - driverClassName................."com.p6spy.engine.spy.P6SpyDriver" 21:48:02.829 [main ] DEBUG com.zaxxer.hikari.HikariConfig - exceptionOverrideClassName......none 21:48:02.829 [main ] DEBUG com.zaxxer.hikari.HikariConfig - healthCheckProperties...........{} 21:48:02.829 [main ] DEBUG com.zaxxer.hikari.HikariConfig - healthCheckRegistry.............none 21:48:02.829 [main ] DEBUG com.zaxxer.hikari.HikariConfig - idleTimeout.....................600000 21:48:02.829 [main ] DEBUG com.zaxxer.hikari.HikariConfig - initializationFailTimeout.......1 21:48:02.829 [main ] DEBUG com.zaxxer.hikari.HikariConfig - isolateInternalQueries..........false 21:48:02.829 [main ] DEBUG com.zaxxer.hikari.HikariConfig - jdbcUrl.........................jdbc:p6spy:sqlite:target/test1.db 21:48:02.830 [main ] DEBUG com.zaxxer.hikari.HikariConfig - keepaliveTime...................0 21:48:02.830 [main ] DEBUG com.zaxxer.hikari.HikariConfig - leakDetectionThreshold..........0 21:48:02.830 [main ] DEBUG com.zaxxer.hikari.HikariConfig - maxLifetime.....................1800000 21:48:02.830 [main ] DEBUG com.zaxxer.hikari.HikariConfig - maximumPoolSize.................10 21:48:02.830 [main ] DEBUG com.zaxxer.hikari.HikariConfig - metricRegistry..................none 21:48:02.830 [main ] DEBUG com.zaxxer.hikari.HikariConfig - metricsTrackerFactory...........none 21:48:02.830 [main ] DEBUG com.zaxxer.hikari.HikariConfig - minimumIdle.....................10 21:48:02.830 [main ] DEBUG com.zaxxer.hikari.HikariConfig - password........................ 21:48:02.830 [main ] DEBUG com.zaxxer.hikari.HikariConfig - poolName........................"HikariPool-2" 21:48:02.830 [main ] DEBUG com.zaxxer.hikari.HikariConfig - readOnly........................false 21:48:02.830 [main ] DEBUG com.zaxxer.hikari.HikariConfig - registerMbeans..................false 21:48:02.831 [main ] DEBUG com.zaxxer.hikari.HikariConfig - scheduledExecutor...............none 21:48:02.831 [main ] DEBUG com.zaxxer.hikari.HikariConfig - schema..........................none 21:48:02.831 [main ] DEBUG com.zaxxer.hikari.HikariConfig - threadFactory...................internal 21:48:02.831 [main ] DEBUG com.zaxxer.hikari.HikariConfig - transactionIsolation............default 21:48:02.831 [main ] DEBUG com.zaxxer.hikari.HikariConfig - username........................none 21:48:02.831 [main ] DEBUG com.zaxxer.hikari.HikariConfig - validationTimeout...............5000 21:48:02.831 [main ] INFO com.zaxxer.hikari.HikariDataSource - HikariPool-2 - Starting... 21:48:02.834 [main ] INFO com.zaxxer.hikari.pool.HikariPool - HikariPool-2 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@4b672ced 21:48:02.834 [main ] INFO com.zaxxer.hikari.HikariDataSource - HikariPool-2 - Start completed. 21:48:02.836 [main ] INFO SQL dialect - HHH000400: Using dialect: org.hibernate.community.dialect.SQLiteDialect 21:48:02.935 [riPool-2 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-2 - Pool stats (total=1, active=0, idle=1, waiting=0) Hibernate: drop table if exists files 21:48:02.939 [l-2 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-2 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@4c88faae 21:48:02.951 [l-2 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-2 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@424f0d4f 21:48:02.963 [l-2 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-2 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@467091de 21:48:02.975 [l-2 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-2 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@3720774 21:48:02.987 [l-2 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-2 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@824c03e 21:48:02.999 [l-2 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-2 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@32bb8100 21:48:03.011 [main ] INFO p6spy - SQL: 72 nanos - drop table if exists files Hibernate: drop table if exists fingerprints 21:48:03.013 [l-2 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-2 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@700718e8 21:48:03.024 [l-2 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-2 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@635df83b 21:48:03.036 [l-2 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-2 - Added connection com.p6spy.engine.wrapper.ConnectionWrapper@4dd6e15e 21:48:03.047 [l-2 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-2 - After adding stats (total=10, active=1, idle=9, waiting=0) 21:48:03.074 [main ] INFO p6spy - SQL: 62 nanos - drop table if exists fingerprints Hibernate: create table files (id varchar(36) not null, path text not null, primary key (id)) 21:48:03.138 [main ] INFO p6spy - SQL: 61 nanos - create table files (id varchar(36) not null, path text not null, primary key (id)) Hibernate: create table fingerprints (id varchar(36) not null, algorithm varchar(16) not null, file_id varchar(36) not null, name text not null, timestamp timestamp not null, fingerprint varchar(32) not null, primary key (id)) 21:48:03.202 [main ] INFO p6spy - SQL: 53 nanos - create table fingerprints (id varchar(36) not null, algorithm varchar(16) not null, file_id varchar(36) not null, name text not null, timestamp timestamp not null, fingerprint varchar(32) not null, primary key (id)) 21:48:03.204 [main ] INFO o.h.e.t.jta.platform.internal.JtaPlatformInitiator - HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform] 21:48:03.210 [main ] INFO o.s.orm.jpa.LocalContainerEntityManagerFactoryBean - Initialized JPA EntityManagerFactory for persistence unit 'default' 21:48:03.361 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - Started JpaDataManagerDaoTest in 0.938 seconds (process running for 11.591) 21:48:03.380 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - **************************** 21:48:03.380 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - TEST "test findManagedFiles" 21:48:03.380 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - **************************** Hibernate: insert into files (path, id) values (?, ?) 21:48:03.440 [main ] INFO p6spy - SQL: 0 nanos - insert into files (path, id) values ('/foo/bar/e047', '00000000-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.446 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000000-0000-0000-0000-000000000000', 'e047', '2020-07-27T07:30:09.000+0000', '80fe035fe88f37471862c5ba5013b472', '00000007-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.447 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000000-0000-0000-0000-000000000000', 'e047', '2020-12-11T03:09:52.000+0000', '80fe035fe88f37471862c5ba5013b472', '00000002-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.448 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000000-0000-0000-0000-000000000000', 'e047', '2021-05-04T09:55:19.000+0000', '80fe035fe88f37471862c5ba5013b472', '00000004-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.449 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000000-0000-0000-0000-000000000000', 'e047', '2022-06-09T18:18:35.000+0000', '80fe035fe88f37471862c5ba5013b472', '00000006-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.451 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000000-0000-0000-0000-000000000000', 'e047', '2022-08-13T14:25:32.000+0000', '80fe035fe88f37471862c5ba5013b472', '00000005-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.452 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000000-0000-0000-0000-000000000000', 'e047', '2022-10-01T14:34:25.000+0000', '80fe035fe88f37471862c5ba5013b472', '00000003-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.454 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000000-0000-0000-0000-000000000000', 'e047', '2023-03-15T07:41:36.000+0000', '80fe035fe88f37471862c5ba5013b472', '00000001-0000-0000-0000-000000000000') Hibernate: insert into files (path, id) values (?, ?) 21:48:03.455 [main ] INFO p6spy - SQL: 0 nanos - insert into files (path, id) values ('/foo/bar/2e10', '00000008-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.457 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000008-0000-0000-0000-000000000000', '2e10', '2022-02-07T16:20:06.000+0000', 'c6043614de10faba15b20b12b72fae53', '00000009-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.458 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000008-0000-0000-0000-000000000000', '2e10', '2022-07-23T22:36:25.000+0000', 'c6043614de10faba15b20b12b72fae53', '0000000a-0000-0000-0000-000000000000') Hibernate: insert into files (path, id) values (?, ?) 21:48:03.462 [main ] INFO p6spy - SQL: 0 nanos - insert into files (path, id) values ('/foo/bar/7d46', '0000000b-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.463 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '0000000b-0000-0000-0000-000000000000', '7d46', '2021-01-07T11:49:15.000+0000', '7f00331093dfc89ac1ec7525c049fa38', '0000000c-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.464 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '0000000b-0000-0000-0000-000000000000', '7d46', '2021-02-04T21:40:37.000+0000', '7f00331093dfc89ac1ec7525c049fa38', '0000000f-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.467 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '0000000b-0000-0000-0000-000000000000', '7d46', '2021-11-30T12:16:29.000+0000', '7f00331093dfc89ac1ec7525c049fa38', '0000000d-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.471 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '0000000b-0000-0000-0000-000000000000', '7d46', '2022-10-12T19:07:44.000+0000', '7f00331093dfc89ac1ec7525c049fa38', '0000000e-0000-0000-0000-000000000000') Hibernate: insert into files (path, id) values (?, ?) 21:48:03.472 [main ] INFO p6spy - SQL: 0 nanos - insert into files (path, id) values ('/foo/bar/3f38', '00000010-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.472 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000010-0000-0000-0000-000000000000', '3f38', '2020-11-27T20:40:07.000+0000', 'acadf7563f2831cab3dbbaeb8afe000f', '00000013-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.473 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000010-0000-0000-0000-000000000000', '3f38', '2021-05-21T01:26:15.000+0000', 'acadf7563f2831cab3dbbaeb8afe000f', '00000011-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.474 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000010-0000-0000-0000-000000000000', '3f38', '2023-04-18T18:11:45.000+0000', 'acadf7563f2831cab3dbbaeb8afe000f', '00000014-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.474 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000010-0000-0000-0000-000000000000', '3f38', '2023-12-21T13:01:22.000+0000', 'acadf7563f2831cab3dbbaeb8afe000f', '00000012-0000-0000-0000-000000000000') Hibernate: insert into files (path, id) values (?, ?) 21:48:03.475 [main ] INFO p6spy - SQL: 0 nanos - insert into files (path, id) values ('/foo/bar/67a2', '00000015-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.475 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000015-0000-0000-0000-000000000000', '67a2', '2020-03-09T17:18:15.000+0000', '0988f9a2700d7d5748715bcd29ada8d4', '0000001a-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.476 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000015-0000-0000-0000-000000000000', '67a2', '2021-05-26T11:37:52.000+0000', '0988f9a2700d7d5748715bcd29ada8d4', '00000016-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.477 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000015-0000-0000-0000-000000000000', '67a2', '2021-06-28T06:23:37.000+0000', '0988f9a2700d7d5748715bcd29ada8d4', '00000018-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.480 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000015-0000-0000-0000-000000000000', '67a2', '2022-09-04T23:44:40.000+0000', '0988f9a2700d7d5748715bcd29ada8d4', '00000017-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.482 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000015-0000-0000-0000-000000000000', '67a2', '2023-04-05T18:47:37.000+0000', '0988f9a2700d7d5748715bcd29ada8d4', '00000019-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.483 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000015-0000-0000-0000-000000000000', '67a2', '2023-04-28T12:13:47.000+0000', '0988f9a2700d7d5748715bcd29ada8d4', '0000001b-0000-0000-0000-000000000000') Hibernate: insert into files (path, id) values (?, ?) 21:48:03.484 [main ] INFO p6spy - SQL: 0 nanos - insert into files (path, id) values ('/foo/bar/f228', '0000001c-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.486 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '0000001c-0000-0000-0000-000000000000', 'f228', '2020-02-15T22:59:25.000+0000', 'f5545c7b0ee25c471665b0f4a57014f2', '00000021-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.488 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '0000001c-0000-0000-0000-000000000000', 'f228', '2020-04-28T10:10:30.000+0000', 'f5545c7b0ee25c471665b0f4a57014f2', '0000001d-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.489 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '0000001c-0000-0000-0000-000000000000', 'f228', '2020-12-29T17:06:31.000+0000', 'f5545c7b0ee25c471665b0f4a57014f2', '0000001f-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.491 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '0000001c-0000-0000-0000-000000000000', 'f228', '2022-09-26T04:15:21.000+0000', 'f5545c7b0ee25c471665b0f4a57014f2', '0000001e-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.493 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '0000001c-0000-0000-0000-000000000000', 'f228', '2022-10-13T07:09:57.000+0000', 'f5545c7b0ee25c471665b0f4a57014f2', '00000020-0000-0000-0000-000000000000') Hibernate: insert into files (path, id) values (?, ?) 21:48:03.494 [main ] INFO p6spy - SQL: 0 nanos - insert into files (path, id) values ('/foo/bar/34c3', '00000022-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.499 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000022-0000-0000-0000-000000000000', '34c3', '2020-06-13T05:07:08.000+0000', 'fc1435e7e4fa8c63894d9f8029f9be2c', '00000025-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.500 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000022-0000-0000-0000-000000000000', '34c3', '2020-09-02T21:37:36.000+0000', 'fc1435e7e4fa8c63894d9f8029f9be2c', '00000023-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.500 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000022-0000-0000-0000-000000000000', '34c3', '2023-09-15T00:33:11.000+0000', 'fc1435e7e4fa8c63894d9f8029f9be2c', '00000026-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.501 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000022-0000-0000-0000-000000000000', '34c3', '2023-09-19T15:15:10.000+0000', 'fc1435e7e4fa8c63894d9f8029f9be2c', '00000024-0000-0000-0000-000000000000') Hibernate: insert into files (path, id) values (?, ?) 21:48:03.501 [main ] INFO p6spy - SQL: 0 nanos - insert into files (path, id) values ('/foo/bar/5712', '00000027-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.502 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000027-0000-0000-0000-000000000000', '5712', '2020-08-22T00:07:21.000+0000', '4590a18f904908454f0d55f2593688dd', '00000028-0000-0000-0000-000000000000') Hibernate: insert into files (path, id) values (?, ?) 21:48:03.502 [main ] INFO p6spy - SQL: 0 nanos - insert into files (path, id) values ('/foo/bar/32cc', '00000029-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.503 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000029-0000-0000-0000-000000000000', '32cc', '2020-09-17T10:35:28.000+0000', 'b9046b121dcde0e58d9e335cb2f8d774', '0000002a-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.504 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '00000029-0000-0000-0000-000000000000', '32cc', '2021-04-26T20:52:42.000+0000', 'b9046b121dcde0e58d9e335cb2f8d774', '0000002b-0000-0000-0000-000000000000') Hibernate: insert into files (path, id) values (?, ?) 21:48:03.504 [main ] INFO p6spy - SQL: 0 nanos - insert into files (path, id) values ('/foo/bar/5252', '0000002c-0000-0000-0000-000000000000') Hibernate: insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values (?, ?, ?, ?, ?, ?) 21:48:03.505 [main ] INFO p6spy - SQL: 0 nanos - insert into fingerprints (algorithm, file_id, name, timestamp, fingerprint, id) values ('md5', '0000002c-0000-0000-0000-000000000000', '5252', '2022-07-24T13:37:41.000+0000', '169a61a24c952f0945d4382dee97bb51', '0000002d-0000-0000-0000-000000000000') 21:48:03.582 [main ] INFO p6spy - SQL: 68 nanos - 21:48:03.596 [main ] TRACE it.tidalwave.util.spi.HierarchicFinderSupport - HierarchicFinderSupport(HierarchicFinderSupport(name=it.tidalwave.datamanager.dao.impl.jpa.JpaDataManagerDao$JpaManagedFileFinder, firstResult=0, maxResults=2147483647, contexts=[], sorters=[]), HierarchicFinderSupport(name=it.tidalwave.util.spring.jpa.JpaRepositoryFinder, firstResult=0, maxResults=2147483647, contexts=[], sorters=[])) 21:48:03.625 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Creating new transaction with name [it.tidalwave.util.spring.jpa.impl.DefaultFinderJpaRepository.findAll]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.625 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: BEGIN - tx definition: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.625 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Opened new EntityManager [SessionImpl(651092951)] for JPA transaction 21:48:03.626 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@41d8e94] 21:48:03.631 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - findAll(0, 2147483647, [JpaSortCriterion(path, ASCENDING)]) Hibernate: select m1_0.id,m1_0.path from files m1_0 order by m1_0.path asc limit ? offset ? 21:48:03.812 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 order by m1_0.path asc limit 2147483647 offset 0 21:48:03.831 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning 10 items 21:48:03.831 [main ] TRACE i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning [ManagedFileEntity@58ef8069(id=00000008-0000-0000-0000-000000000000, path=/foo/bar/2e10, fingerprints=not initialized), ManagedFileEntity@10706411(id=00000029-0000-0000-0000-000000000000, path=/foo/bar/32cc, fingerprints=not initialized), ManagedFileEntity@dcd6f19(id=00000022-0000-0000-0000-000000000000, path=/foo/bar/34c3, fingerprints=not initialized), ManagedFileEntity@df31866(id=00000010-0000-0000-0000-000000000000, path=/foo/bar/3f38, fingerprints=not initialized), ManagedFileEntity@68d27530(id=0000002c-0000-0000-0000-000000000000, path=/foo/bar/5252, fingerprints=not initialized), ManagedFileEntity@66e6bb8e(id=00000027-0000-0000-0000-000000000000, path=/foo/bar/5712, fingerprints=not initialized), ManagedFileEntity@185010b5(id=00000015-0000-0000-0000-000000000000, path=/foo/bar/67a2, fingerprints=not initialized), ManagedFileEntity@25f92ffd(id=0000000b-0000-0000-0000-000000000000, path=/foo/bar/7d46, fingerprints=not initialized), ManagedFileEntity@5a93dccc(id=00000000-0000-0000-0000-000000000000, path=/foo/bar/e047, fingerprints=not initialized), ManagedFileEntity@469a9010(id=0000001c-0000-0000-0000-000000000000, path=/foo/bar/f228, fingerprints=not initialized)] 21:48:03.835 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Initiating transaction commit 21:48:03.836 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: COMMIT 21:48:03.836 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(651092951)] 21:48:03.837 [main ] INFO p6spy - SQL: 0 nanos - 21:48:03.837 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Closing JPA EntityManager [SessionImpl(651092951)] after transaction 21:48:03.844 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - Asserting that lazy collection of fingerprints not fetched yet... 21:48:03.845 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - Triggering lazy fetch... 21:48:03.846 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Creating new transaction with name [it.tidalwave.util.spring.jpa.impl.DefaultFinderJpaRepository.fetch]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.846 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: BEGIN - tx definition: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.846 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Opened new EntityManager [SessionImpl(609601884)] for JPA transaction 21:48:03.847 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@380d3099] 21:48:03.847 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - fetch(ManagedFileEntity, id=00000008-0000-0000-0000-000000000000) Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:03.848 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='00000008-0000-0000-0000-000000000000' Hibernate: select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id=? order by f1_0.timestamp asc 21:48:03.856 [main ] INFO p6spy - SQL: 0 nanos - select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id='00000008-0000-0000-0000-000000000000' order by f1_0.timestamp asc 21:48:03.859 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning 2 items 21:48:03.859 [main ] TRACE i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning [FingerprintEntity(id=00000009-0000-0000-0000-000000000000, name=2e10, algorithm=md5, value=c6043614de10faba15b20b12b72fae53, timestamp=2022-02-07T16:20:06, fileId=00000008-0000-0000-0000-000000000000), FingerprintEntity(id=0000000a-0000-0000-0000-000000000000, name=2e10, algorithm=md5, value=c6043614de10faba15b20b12b72fae53, timestamp=2022-07-23T22:36:25, fileId=00000008-0000-0000-0000-000000000000)] 21:48:03.864 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Initiating transaction commit 21:48:03.864 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: COMMIT 21:48:03.864 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(609601884)] 21:48:03.865 [main ] INFO p6spy - SQL: 0 nanos - 21:48:03.865 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Closing JPA EntityManager [SessionImpl(609601884)] after transaction 21:48:03.866 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Creating new transaction with name [it.tidalwave.util.spring.jpa.impl.DefaultFinderJpaRepository.fetch]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.867 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: BEGIN - tx definition: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.867 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Opened new EntityManager [SessionImpl(1204124943)] for JPA transaction 21:48:03.867 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@7d6ec39c] 21:48:03.867 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - fetch(ManagedFileEntity, id=00000029-0000-0000-0000-000000000000) Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:03.868 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='00000029-0000-0000-0000-000000000000' Hibernate: select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id=? order by f1_0.timestamp asc 21:48:03.870 [main ] INFO p6spy - SQL: 0 nanos - select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id='00000029-0000-0000-0000-000000000000' order by f1_0.timestamp asc 21:48:03.871 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning 2 items 21:48:03.871 [main ] TRACE i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning [FingerprintEntity(id=0000002a-0000-0000-0000-000000000000, name=32cc, algorithm=md5, value=b9046b121dcde0e58d9e335cb2f8d774, timestamp=2020-09-17T10:35:28, fileId=00000029-0000-0000-0000-000000000000), FingerprintEntity(id=0000002b-0000-0000-0000-000000000000, name=32cc, algorithm=md5, value=b9046b121dcde0e58d9e335cb2f8d774, timestamp=2021-04-26T20:52:42, fileId=00000029-0000-0000-0000-000000000000)] 21:48:03.871 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Initiating transaction commit 21:48:03.871 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: COMMIT 21:48:03.871 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(1204124943)] 21:48:03.872 [main ] INFO p6spy - SQL: 0 nanos - 21:48:03.872 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Closing JPA EntityManager [SessionImpl(1204124943)] after transaction 21:48:03.872 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Creating new transaction with name [it.tidalwave.util.spring.jpa.impl.DefaultFinderJpaRepository.fetch]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.872 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: BEGIN - tx definition: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.872 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Opened new EntityManager [SessionImpl(1640732477)] for JPA transaction 21:48:03.872 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@3353d096] 21:48:03.873 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - fetch(ManagedFileEntity, id=00000022-0000-0000-0000-000000000000) Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:03.873 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='00000022-0000-0000-0000-000000000000' Hibernate: select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id=? order by f1_0.timestamp asc 21:48:03.874 [main ] INFO p6spy - SQL: 0 nanos - select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id='00000022-0000-0000-0000-000000000000' order by f1_0.timestamp asc 21:48:03.876 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning 4 items 21:48:03.876 [main ] TRACE i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning [FingerprintEntity(id=00000025-0000-0000-0000-000000000000, name=34c3, algorithm=md5, value=fc1435e7e4fa8c63894d9f8029f9be2c, timestamp=2020-06-13T05:07:08, fileId=00000022-0000-0000-0000-000000000000), FingerprintEntity(id=00000023-0000-0000-0000-000000000000, name=34c3, algorithm=md5, value=fc1435e7e4fa8c63894d9f8029f9be2c, timestamp=2020-09-02T21:37:36, fileId=00000022-0000-0000-0000-000000000000), FingerprintEntity(id=00000026-0000-0000-0000-000000000000, name=34c3, algorithm=md5, value=fc1435e7e4fa8c63894d9f8029f9be2c, timestamp=2023-09-15T00:33:11, fileId=00000022-0000-0000-0000-000000000000), FingerprintEntity(id=00000024-0000-0000-0000-000000000000, name=34c3, algorithm=md5, value=fc1435e7e4fa8c63894d9f8029f9be2c, timestamp=2023-09-19T15:15:10, fileId=00000022-0000-0000-0000-000000000000)] 21:48:03.876 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Initiating transaction commit 21:48:03.876 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: COMMIT 21:48:03.876 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(1640732477)] 21:48:03.877 [main ] INFO p6spy - SQL: 0 nanos - 21:48:03.877 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Closing JPA EntityManager [SessionImpl(1640732477)] after transaction 21:48:03.878 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Creating new transaction with name [it.tidalwave.util.spring.jpa.impl.DefaultFinderJpaRepository.fetch]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.878 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: BEGIN - tx definition: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.878 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Opened new EntityManager [SessionImpl(1276416078)] for JPA transaction 21:48:03.878 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@256088a7] 21:48:03.878 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - fetch(ManagedFileEntity, id=00000010-0000-0000-0000-000000000000) Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:03.879 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='00000010-0000-0000-0000-000000000000' Hibernate: select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id=? order by f1_0.timestamp asc 21:48:03.880 [main ] INFO p6spy - SQL: 0 nanos - select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id='00000010-0000-0000-0000-000000000000' order by f1_0.timestamp asc 21:48:03.882 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning 4 items 21:48:03.882 [main ] TRACE i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning [FingerprintEntity(id=00000013-0000-0000-0000-000000000000, name=3f38, algorithm=md5, value=acadf7563f2831cab3dbbaeb8afe000f, timestamp=2020-11-27T20:40:07, fileId=00000010-0000-0000-0000-000000000000), FingerprintEntity(id=00000011-0000-0000-0000-000000000000, name=3f38, algorithm=md5, value=acadf7563f2831cab3dbbaeb8afe000f, timestamp=2021-05-21T01:26:15, fileId=00000010-0000-0000-0000-000000000000), FingerprintEntity(id=00000014-0000-0000-0000-000000000000, name=3f38, algorithm=md5, value=acadf7563f2831cab3dbbaeb8afe000f, timestamp=2023-04-18T18:11:45, fileId=00000010-0000-0000-0000-000000000000), FingerprintEntity(id=00000012-0000-0000-0000-000000000000, name=3f38, algorithm=md5, value=acadf7563f2831cab3dbbaeb8afe000f, timestamp=2023-12-21T13:01:22, fileId=00000010-0000-0000-0000-000000000000)] 21:48:03.882 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Initiating transaction commit 21:48:03.882 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: COMMIT 21:48:03.882 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(1276416078)] 21:48:03.883 [main ] INFO p6spy - SQL: 0 nanos - 21:48:03.883 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Closing JPA EntityManager [SessionImpl(1276416078)] after transaction 21:48:03.883 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Creating new transaction with name [it.tidalwave.util.spring.jpa.impl.DefaultFinderJpaRepository.fetch]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.883 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: BEGIN - tx definition: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.884 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Opened new EntityManager [SessionImpl(906929433)] for JPA transaction 21:48:03.884 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@2e643cad] 21:48:03.884 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - fetch(ManagedFileEntity, id=0000002c-0000-0000-0000-000000000000) Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:03.885 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='0000002c-0000-0000-0000-000000000000' Hibernate: select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id=? order by f1_0.timestamp asc 21:48:03.886 [main ] INFO p6spy - SQL: 0 nanos - select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id='0000002c-0000-0000-0000-000000000000' order by f1_0.timestamp asc 21:48:03.886 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning 1 items 21:48:03.886 [main ] TRACE i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning [FingerprintEntity(id=0000002d-0000-0000-0000-000000000000, name=5252, algorithm=md5, value=169a61a24c952f0945d4382dee97bb51, timestamp=2022-07-24T13:37:41, fileId=0000002c-0000-0000-0000-000000000000)] 21:48:03.886 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Initiating transaction commit 21:48:03.887 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: COMMIT 21:48:03.887 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(906929433)] 21:48:03.887 [main ] INFO p6spy - SQL: 0 nanos - 21:48:03.887 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Closing JPA EntityManager [SessionImpl(906929433)] after transaction 21:48:03.888 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Creating new transaction with name [it.tidalwave.util.spring.jpa.impl.DefaultFinderJpaRepository.fetch]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.888 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: BEGIN - tx definition: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.888 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Opened new EntityManager [SessionImpl(735996273)] for JPA transaction 21:48:03.888 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@3f2054d] 21:48:03.888 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - fetch(ManagedFileEntity, id=00000027-0000-0000-0000-000000000000) Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:03.889 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='00000027-0000-0000-0000-000000000000' Hibernate: select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id=? order by f1_0.timestamp asc 21:48:03.890 [main ] INFO p6spy - SQL: 0 nanos - select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id='00000027-0000-0000-0000-000000000000' order by f1_0.timestamp asc 21:48:03.891 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning 1 items 21:48:03.891 [main ] TRACE i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning [FingerprintEntity(id=00000028-0000-0000-0000-000000000000, name=5712, algorithm=md5, value=4590a18f904908454f0d55f2593688dd, timestamp=2020-08-22T00:07:21, fileId=00000027-0000-0000-0000-000000000000)] 21:48:03.891 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Initiating transaction commit 21:48:03.891 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: COMMIT 21:48:03.891 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(735996273)] 21:48:03.891 [main ] INFO p6spy - SQL: 0 nanos - 21:48:03.892 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Closing JPA EntityManager [SessionImpl(735996273)] after transaction 21:48:03.892 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Creating new transaction with name [it.tidalwave.util.spring.jpa.impl.DefaultFinderJpaRepository.fetch]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.892 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: BEGIN - tx definition: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.892 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Opened new EntityManager [SessionImpl(1148816769)] for JPA transaction 21:48:03.892 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@605d67f8] 21:48:03.892 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - fetch(ManagedFileEntity, id=00000015-0000-0000-0000-000000000000) Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:03.893 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='00000015-0000-0000-0000-000000000000' Hibernate: select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id=? order by f1_0.timestamp asc 21:48:03.894 [main ] INFO p6spy - SQL: 0 nanos - select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id='00000015-0000-0000-0000-000000000000' order by f1_0.timestamp asc 21:48:03.896 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning 6 items 21:48:03.896 [main ] TRACE i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning [FingerprintEntity(id=0000001a-0000-0000-0000-000000000000, name=67a2, algorithm=md5, value=0988f9a2700d7d5748715bcd29ada8d4, timestamp=2020-03-09T17:18:15, fileId=00000015-0000-0000-0000-000000000000), FingerprintEntity(id=00000016-0000-0000-0000-000000000000, name=67a2, algorithm=md5, value=0988f9a2700d7d5748715bcd29ada8d4, timestamp=2021-05-26T11:37:52, fileId=00000015-0000-0000-0000-000000000000), FingerprintEntity(id=00000018-0000-0000-0000-000000000000, name=67a2, algorithm=md5, value=0988f9a2700d7d5748715bcd29ada8d4, timestamp=2021-06-28T06:23:37, fileId=00000015-0000-0000-0000-000000000000), FingerprintEntity(id=00000017-0000-0000-0000-000000000000, name=67a2, algorithm=md5, value=0988f9a2700d7d5748715bcd29ada8d4, timestamp=2022-09-04T23:44:40, fileId=00000015-0000-0000-0000-000000000000), FingerprintEntity(id=00000019-0000-0000-0000-000000000000, name=67a2, algorithm=md5, value=0988f9a2700d7d5748715bcd29ada8d4, timestamp=2023-04-05T18:47:37, fileId=00000015-0000-0000-0000-000000000000), FingerprintEntity(id=0000001b-0000-0000-0000-000000000000, name=67a2, algorithm=md5, value=0988f9a2700d7d5748715bcd29ada8d4, timestamp=2023-04-28T12:13:47, fileId=00000015-0000-0000-0000-000000000000)] 21:48:03.897 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Initiating transaction commit 21:48:03.897 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: COMMIT 21:48:03.897 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(1148816769)] 21:48:03.898 [main ] INFO p6spy - SQL: 0 nanos - 21:48:03.898 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Closing JPA EntityManager [SessionImpl(1148816769)] after transaction 21:48:03.899 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Creating new transaction with name [it.tidalwave.util.spring.jpa.impl.DefaultFinderJpaRepository.fetch]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.899 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: BEGIN - tx definition: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.899 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Opened new EntityManager [SessionImpl(875077215)] for JPA transaction 21:48:03.899 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@363843e] 21:48:03.899 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - fetch(ManagedFileEntity, id=0000000b-0000-0000-0000-000000000000) Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:03.900 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='0000000b-0000-0000-0000-000000000000' Hibernate: select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id=? order by f1_0.timestamp asc 21:48:03.901 [main ] INFO p6spy - SQL: 0 nanos - select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id='0000000b-0000-0000-0000-000000000000' order by f1_0.timestamp asc 21:48:03.903 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning 4 items 21:48:03.903 [main ] TRACE i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning [FingerprintEntity(id=0000000c-0000-0000-0000-000000000000, name=7d46, algorithm=md5, value=7f00331093dfc89ac1ec7525c049fa38, timestamp=2021-01-07T11:49:15, fileId=0000000b-0000-0000-0000-000000000000), FingerprintEntity(id=0000000f-0000-0000-0000-000000000000, name=7d46, algorithm=md5, value=7f00331093dfc89ac1ec7525c049fa38, timestamp=2021-02-04T21:40:37, fileId=0000000b-0000-0000-0000-000000000000), FingerprintEntity(id=0000000d-0000-0000-0000-000000000000, name=7d46, algorithm=md5, value=7f00331093dfc89ac1ec7525c049fa38, timestamp=2021-11-30T12:16:29, fileId=0000000b-0000-0000-0000-000000000000), FingerprintEntity(id=0000000e-0000-0000-0000-000000000000, name=7d46, algorithm=md5, value=7f00331093dfc89ac1ec7525c049fa38, timestamp=2022-10-12T19:07:44, fileId=0000000b-0000-0000-0000-000000000000)] 21:48:03.903 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Initiating transaction commit 21:48:03.903 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: COMMIT 21:48:03.903 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(875077215)] 21:48:03.904 [main ] INFO p6spy - SQL: 0 nanos - 21:48:03.904 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Closing JPA EntityManager [SessionImpl(875077215)] after transaction 21:48:03.904 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Creating new transaction with name [it.tidalwave.util.spring.jpa.impl.DefaultFinderJpaRepository.fetch]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.904 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: BEGIN - tx definition: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.904 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Opened new EntityManager [SessionImpl(1022566764)] for JPA transaction 21:48:03.905 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@5bc2dbcd] 21:48:03.905 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - fetch(ManagedFileEntity, id=00000000-0000-0000-0000-000000000000) Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:03.905 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='00000000-0000-0000-0000-000000000000' Hibernate: select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id=? order by f1_0.timestamp asc 21:48:03.907 [main ] INFO p6spy - SQL: 0 nanos - select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id='00000000-0000-0000-0000-000000000000' order by f1_0.timestamp asc 21:48:03.908 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning 7 items 21:48:03.909 [main ] TRACE i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning [FingerprintEntity(id=00000007-0000-0000-0000-000000000000, name=e047, algorithm=md5, value=80fe035fe88f37471862c5ba5013b472, timestamp=2020-07-27T07:30:09, fileId=00000000-0000-0000-0000-000000000000), FingerprintEntity(id=00000002-0000-0000-0000-000000000000, name=e047, algorithm=md5, value=80fe035fe88f37471862c5ba5013b472, timestamp=2020-12-11T03:09:52, fileId=00000000-0000-0000-0000-000000000000), FingerprintEntity(id=00000004-0000-0000-0000-000000000000, name=e047, algorithm=md5, value=80fe035fe88f37471862c5ba5013b472, timestamp=2021-05-04T09:55:19, fileId=00000000-0000-0000-0000-000000000000), FingerprintEntity(id=00000006-0000-0000-0000-000000000000, name=e047, algorithm=md5, value=80fe035fe88f37471862c5ba5013b472, timestamp=2022-06-09T18:18:35, fileId=00000000-0000-0000-0000-000000000000), FingerprintEntity(id=00000005-0000-0000-0000-000000000000, name=e047, algorithm=md5, value=80fe035fe88f37471862c5ba5013b472, timestamp=2022-08-13T14:25:32, fileId=00000000-0000-0000-0000-000000000000), FingerprintEntity(id=00000003-0000-0000-0000-000000000000, name=e047, algorithm=md5, value=80fe035fe88f37471862c5ba5013b472, timestamp=2022-10-01T14:34:25, fileId=00000000-0000-0000-0000-000000000000), FingerprintEntity(id=00000001-0000-0000-0000-000000000000, name=e047, algorithm=md5, value=80fe035fe88f37471862c5ba5013b472, timestamp=2023-03-15T07:41:36, fileId=00000000-0000-0000-0000-000000000000)] 21:48:03.909 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Initiating transaction commit 21:48:03.909 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: COMMIT 21:48:03.909 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(1022566764)] 21:48:03.910 [main ] INFO p6spy - SQL: 0 nanos - 21:48:03.910 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Closing JPA EntityManager [SessionImpl(1022566764)] after transaction 21:48:03.910 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Creating new transaction with name [it.tidalwave.util.spring.jpa.impl.DefaultFinderJpaRepository.fetch]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.910 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: BEGIN - tx definition: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.911 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Opened new EntityManager [SessionImpl(1562550349)] for JPA transaction 21:48:03.911 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@4e32e1f9] 21:48:03.911 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - fetch(ManagedFileEntity, id=0000001c-0000-0000-0000-000000000000) Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:03.912 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='0000001c-0000-0000-0000-000000000000' Hibernate: select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id=? order by f1_0.timestamp asc 21:48:03.913 [main ] INFO p6spy - SQL: 0 nanos - select f1_0.file_id,f1_0.id,f1_0.algorithm,f1_0.name,f1_0.timestamp,f1_0.fingerprint from fingerprints f1_0 where f1_0.file_id='0000001c-0000-0000-0000-000000000000' order by f1_0.timestamp asc 21:48:03.914 [main ] INFO i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning 5 items 21:48:03.915 [main ] TRACE i.t.u.spring.jpa.impl.DefaultFinderJpaRepository - >>>> returning [FingerprintEntity(id=00000021-0000-0000-0000-000000000000, name=f228, algorithm=md5, value=f5545c7b0ee25c471665b0f4a57014f2, timestamp=2020-02-15T22:59:25, fileId=0000001c-0000-0000-0000-000000000000), FingerprintEntity(id=0000001d-0000-0000-0000-000000000000, name=f228, algorithm=md5, value=f5545c7b0ee25c471665b0f4a57014f2, timestamp=2020-04-28T10:10:30, fileId=0000001c-0000-0000-0000-000000000000), FingerprintEntity(id=0000001f-0000-0000-0000-000000000000, name=f228, algorithm=md5, value=f5545c7b0ee25c471665b0f4a57014f2, timestamp=2020-12-29T17:06:31, fileId=0000001c-0000-0000-0000-000000000000), FingerprintEntity(id=0000001e-0000-0000-0000-000000000000, name=f228, algorithm=md5, value=f5545c7b0ee25c471665b0f4a57014f2, timestamp=2022-09-26T04:15:21, fileId=0000001c-0000-0000-0000-000000000000), FingerprintEntity(id=00000020-0000-0000-0000-000000000000, name=f228, algorithm=md5, value=f5545c7b0ee25c471665b0f4a57014f2, timestamp=2022-10-13T07:09:57, fileId=0000001c-0000-0000-0000-000000000000)] 21:48:03.915 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Initiating transaction commit 21:48:03.915 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: COMMIT 21:48:03.915 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(1562550349)] 21:48:03.916 [main ] INFO p6spy - SQL: 0 nanos - 21:48:03.916 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Closing JPA EntityManager [SessionImpl(1562550349)] after transaction 21:48:03.916 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - Actual result: 21:48:03.916 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - ManagedFile(id=00000008-0000-0000-0000-000000000000, path=/foo/bar/2e10, fingerprints=LazySupplier(ref=[Fingerprint(id=00000009-0000-0000-0000-000000000000, name=2e10, algorithm=md5, fingerprint=c6043614de10faba15b20b12b72fae53, timestamp=2022-02-07T16:20:06), Fingerprint(id=0000000a-0000-0000-0000-000000000000, name=2e10, algorithm=md5, fingerprint=c6043614de10faba15b20b12b72fae53, timestamp=2022-07-23T22:36:25)])) 21:48:03.920 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - ManagedFile(id=00000029-0000-0000-0000-000000000000, path=/foo/bar/32cc, fingerprints=LazySupplier(ref=[Fingerprint(id=0000002a-0000-0000-0000-000000000000, name=32cc, algorithm=md5, fingerprint=b9046b121dcde0e58d9e335cb2f8d774, timestamp=2020-09-17T10:35:28), Fingerprint(id=0000002b-0000-0000-0000-000000000000, name=32cc, algorithm=md5, fingerprint=b9046b121dcde0e58d9e335cb2f8d774, timestamp=2021-04-26T20:52:42)])) 21:48:03.920 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - ManagedFile(id=00000022-0000-0000-0000-000000000000, path=/foo/bar/34c3, fingerprints=LazySupplier(ref=[Fingerprint(id=00000025-0000-0000-0000-000000000000, name=34c3, algorithm=md5, fingerprint=fc1435e7e4fa8c63894d9f8029f9be2c, timestamp=2020-06-13T05:07:08), Fingerprint(id=00000023-0000-0000-0000-000000000000, name=34c3, algorithm=md5, fingerprint=fc1435e7e4fa8c63894d9f8029f9be2c, timestamp=2020-09-02T21:37:36), Fingerprint(id=00000026-0000-0000-0000-000000000000, name=34c3, algorithm=md5, fingerprint=fc1435e7e4fa8c63894d9f8029f9be2c, timestamp=2023-09-15T00:33:11), Fingerprint(id=00000024-0000-0000-0000-000000000000, name=34c3, algorithm=md5, fingerprint=fc1435e7e4fa8c63894d9f8029f9be2c, timestamp=2023-09-19T15:15:10)])) 21:48:03.920 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - ManagedFile(id=00000010-0000-0000-0000-000000000000, path=/foo/bar/3f38, fingerprints=LazySupplier(ref=[Fingerprint(id=00000013-0000-0000-0000-000000000000, name=3f38, algorithm=md5, fingerprint=acadf7563f2831cab3dbbaeb8afe000f, timestamp=2020-11-27T20:40:07), Fingerprint(id=00000011-0000-0000-0000-000000000000, name=3f38, algorithm=md5, fingerprint=acadf7563f2831cab3dbbaeb8afe000f, timestamp=2021-05-21T01:26:15), Fingerprint(id=00000014-0000-0000-0000-000000000000, name=3f38, algorithm=md5, fingerprint=acadf7563f2831cab3dbbaeb8afe000f, timestamp=2023-04-18T18:11:45), Fingerprint(id=00000012-0000-0000-0000-000000000000, name=3f38, algorithm=md5, fingerprint=acadf7563f2831cab3dbbaeb8afe000f, timestamp=2023-12-21T13:01:22)])) 21:48:03.920 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - ManagedFile(id=0000002c-0000-0000-0000-000000000000, path=/foo/bar/5252, fingerprints=LazySupplier(ref=[Fingerprint(id=0000002d-0000-0000-0000-000000000000, name=5252, algorithm=md5, fingerprint=169a61a24c952f0945d4382dee97bb51, timestamp=2022-07-24T13:37:41)])) 21:48:03.920 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - ManagedFile(id=00000027-0000-0000-0000-000000000000, path=/foo/bar/5712, fingerprints=LazySupplier(ref=[Fingerprint(id=00000028-0000-0000-0000-000000000000, name=5712, algorithm=md5, fingerprint=4590a18f904908454f0d55f2593688dd, timestamp=2020-08-22T00:07:21)])) 21:48:03.920 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - ManagedFile(id=00000015-0000-0000-0000-000000000000, path=/foo/bar/67a2, fingerprints=LazySupplier(ref=[Fingerprint(id=0000001a-0000-0000-0000-000000000000, name=67a2, algorithm=md5, fingerprint=0988f9a2700d7d5748715bcd29ada8d4, timestamp=2020-03-09T17:18:15), Fingerprint(id=00000016-0000-0000-0000-000000000000, name=67a2, algorithm=md5, fingerprint=0988f9a2700d7d5748715bcd29ada8d4, timestamp=2021-05-26T11:37:52), Fingerprint(id=00000018-0000-0000-0000-000000000000, name=67a2, algorithm=md5, fingerprint=0988f9a2700d7d5748715bcd29ada8d4, timestamp=2021-06-28T06:23:37), Fingerprint(id=00000017-0000-0000-0000-000000000000, name=67a2, algorithm=md5, fingerprint=0988f9a2700d7d5748715bcd29ada8d4, timestamp=2022-09-04T23:44:40), Fingerprint(id=00000019-0000-0000-0000-000000000000, name=67a2, algorithm=md5, fingerprint=0988f9a2700d7d5748715bcd29ada8d4, timestamp=2023-04-05T18:47:37), Fingerprint(id=0000001b-0000-0000-0000-000000000000, name=67a2, algorithm=md5, fingerprint=0988f9a2700d7d5748715bcd29ada8d4, timestamp=2023-04-28T12:13:47)])) 21:48:03.920 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - ManagedFile(id=0000000b-0000-0000-0000-000000000000, path=/foo/bar/7d46, fingerprints=LazySupplier(ref=[Fingerprint(id=0000000c-0000-0000-0000-000000000000, name=7d46, algorithm=md5, fingerprint=7f00331093dfc89ac1ec7525c049fa38, timestamp=2021-01-07T11:49:15), Fingerprint(id=0000000f-0000-0000-0000-000000000000, name=7d46, algorithm=md5, fingerprint=7f00331093dfc89ac1ec7525c049fa38, timestamp=2021-02-04T21:40:37), Fingerprint(id=0000000d-0000-0000-0000-000000000000, name=7d46, algorithm=md5, fingerprint=7f00331093dfc89ac1ec7525c049fa38, timestamp=2021-11-30T12:16:29), Fingerprint(id=0000000e-0000-0000-0000-000000000000, name=7d46, algorithm=md5, fingerprint=7f00331093dfc89ac1ec7525c049fa38, timestamp=2022-10-12T19:07:44)])) 21:48:03.920 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - ManagedFile(id=00000000-0000-0000-0000-000000000000, path=/foo/bar/e047, fingerprints=LazySupplier(ref=[Fingerprint(id=00000007-0000-0000-0000-000000000000, name=e047, algorithm=md5, fingerprint=80fe035fe88f37471862c5ba5013b472, timestamp=2020-07-27T07:30:09), Fingerprint(id=00000002-0000-0000-0000-000000000000, name=e047, algorithm=md5, fingerprint=80fe035fe88f37471862c5ba5013b472, timestamp=2020-12-11T03:09:52), Fingerprint(id=00000004-0000-0000-0000-000000000000, name=e047, algorithm=md5, fingerprint=80fe035fe88f37471862c5ba5013b472, timestamp=2021-05-04T09:55:19), Fingerprint(id=00000006-0000-0000-0000-000000000000, name=e047, algorithm=md5, fingerprint=80fe035fe88f37471862c5ba5013b472, timestamp=2022-06-09T18:18:35), Fingerprint(id=00000005-0000-0000-0000-000000000000, name=e047, algorithm=md5, fingerprint=80fe035fe88f37471862c5ba5013b472, timestamp=2022-08-13T14:25:32), Fingerprint(id=00000003-0000-0000-0000-000000000000, name=e047, algorithm=md5, fingerprint=80fe035fe88f37471862c5ba5013b472, timestamp=2022-10-01T14:34:25), Fingerprint(id=00000001-0000-0000-0000-000000000000, name=e047, algorithm=md5, fingerprint=80fe035fe88f37471862c5ba5013b472, timestamp=2023-03-15T07:41:36)])) 21:48:03.921 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - ManagedFile(id=0000001c-0000-0000-0000-000000000000, path=/foo/bar/f228, fingerprints=LazySupplier(ref=[Fingerprint(id=00000021-0000-0000-0000-000000000000, name=f228, algorithm=md5, fingerprint=f5545c7b0ee25c471665b0f4a57014f2, timestamp=2020-02-15T22:59:25), Fingerprint(id=0000001d-0000-0000-0000-000000000000, name=f228, algorithm=md5, fingerprint=f5545c7b0ee25c471665b0f4a57014f2, timestamp=2020-04-28T10:10:30), Fingerprint(id=0000001f-0000-0000-0000-000000000000, name=f228, algorithm=md5, fingerprint=f5545c7b0ee25c471665b0f4a57014f2, timestamp=2020-12-29T17:06:31), Fingerprint(id=0000001e-0000-0000-0000-000000000000, name=f228, algorithm=md5, fingerprint=f5545c7b0ee25c471665b0f4a57014f2, timestamp=2022-09-26T04:15:21), Fingerprint(id=00000020-0000-0000-0000-000000000000, name=f228, algorithm=md5, fingerprint=f5545c7b0ee25c471665b0f4a57014f2, timestamp=2022-10-13T07:09:57)])) 21:48:03.933 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - TEST PASSED in 548 msec 21:48:03.934 [main ] INFO i.t.datamanager.dao.impl.jpa.JpaDataManagerDaoTest - 21:48:03.943 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Creating new transaction with name [it.tidalwave.datamanager.dao.impl.jpa.ManagedFileEntityTest.test_equals_and_hashCode]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.943 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: BEGIN - tx definition: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:03.943 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Opened new EntityManager [SessionImpl(1953058566)] for JPA transaction 21:48:03.944 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@16c69c47] 21:48:03.945 [main ] INFO i.t.datamanager.dao.impl.jpa.ManagedFileEntityTest - ******************************* 21:48:03.945 [main ] INFO i.t.datamanager.dao.impl.jpa.ManagedFileEntityTest - TEST "test equals and hashCode" 21:48:03.945 [main ] INFO i.t.datamanager.dao.impl.jpa.ManagedFileEntityTest - ******************************* Hibernate: insert into files (path, id) values (?, ?) 21:48:03.949 [main ] INFO p6spy - SQL: 0 nanos - insert into files (path, id) values ('path', 'id') 21:48:03.994 [main ] INFO p6spy - SQL: 44 nanos - Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:04.005 [main ] INFO p6spy - SQL: 1 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='id' 21:48:04.007 [main ] INFO p6spy - SQL: 0 nanos - Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:04.009 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='id' 21:48:04.016 [main ] INFO p6spy - SQL: 0 nanos - Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:04.019 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='id' 21:48:04.023 [main ] INFO p6spy - SQL: 0 nanos - Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:04.027 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='id' 21:48:04.030 [main ] INFO p6spy - SQL: 0 nanos - Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:04.032 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='id' 21:48:04.033 [main ] INFO p6spy - SQL: 0 nanos - Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:04.035 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='id' 21:48:04.050 [main ] INFO p6spy - SQL: 0 nanos - Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:04.060 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='id' Hibernate: delete from files where id=? 21:48:04.065 [main ] INFO p6spy - SQL: 0 nanos - delete from files where id='id' 21:48:04.125 [main ] INFO p6spy - SQL: 58 nanos - 21:48:04.128 [main ] INFO i.t.datamanager.dao.impl.jpa.ManagedFileEntityTest - TEST PASSED in 181 msec 21:48:04.128 [main ] INFO i.t.datamanager.dao.impl.jpa.ManagedFileEntityTest - 21:48:04.137 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Initiating transaction rollback 21:48:04.138 [main ] WARN i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: ROLLBACK 21:48:04.138 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Rolling back JPA transaction on EntityManager [SessionImpl(1953058566)] 21:48:04.138 [main ] INFO p6spy - SQL: 0 nanos - 21:48:04.139 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Closing JPA EntityManager [SessionImpl(1953058566)] after transaction 21:48:04.142 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Creating new transaction with name [it.tidalwave.datamanager.dao.impl.jpa.ManagedFileEntityTest.test_toString_with_proxy]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:04.142 [main ] INFO i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: BEGIN - tx definition: PROPAGATION_REQUIRED,ISOLATION_DEFAULT 21:48:04.142 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Opened new EntityManager [SessionImpl(1397751331)] for JPA transaction 21:48:04.142 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@5f57cecd] 21:48:04.146 [main ] INFO i.t.datamanager.dao.impl.jpa.ManagedFileEntityTest - ******************************* 21:48:04.147 [main ] INFO i.t.datamanager.dao.impl.jpa.ManagedFileEntityTest - TEST "test toString with proxy" 21:48:04.147 [main ] INFO i.t.datamanager.dao.impl.jpa.ManagedFileEntityTest - ******************************* Hibernate: insert into files (path, id) values (?, ?) 21:48:04.153 [main ] INFO p6spy - SQL: 0 nanos - insert into files (path, id) values ('path', 'id2') 21:48:04.226 [main ] INFO p6spy - SQL: 72 nanos - Hibernate: select m1_0.id,m1_0.path from files m1_0 where m1_0.id=? 21:48:04.236 [main ] INFO p6spy - SQL: 0 nanos - select m1_0.id,m1_0.path from files m1_0 where m1_0.id='id2' 21:48:04.238 [main ] INFO p6spy - SQL: 0 nanos - 21:48:04.241 [main ] INFO i.t.datamanager.dao.impl.jpa.ManagedFileEntityTest - TEST PASSED in 93 msec 21:48:04.241 [main ] INFO i.t.datamanager.dao.impl.jpa.ManagedFileEntityTest - 21:48:04.247 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Initiating transaction rollback 21:48:04.248 [main ] WARN i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - SQL: ROLLBACK 21:48:04.250 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Rolling back JPA transaction on EntityManager [SessionImpl(1397751331)] 21:48:04.250 [main ] INFO p6spy - SQL: 0 nanos - 21:48:04.251 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Closing JPA EntityManager [SessionImpl(1397751331)] after transaction 21:48:04.259 [main ] INFO i.t.datamanager.dao.impl.jpa.ManagedFileEntityTest - ********************************** 21:48:04.259 [main ] INFO i.t.datamanager.dao.impl.jpa.ManagedFileEntityTest - TEST "test toString without proxy" 21:48:04.259 [main ] INFO i.t.datamanager.dao.impl.jpa.ManagedFileEntityTest - ********************************** 21:48:04.268 [main ] INFO i.t.datamanager.dao.impl.jpa.ManagedFileEntityTest - TEST PASSED in 8 msec 21:48:04.268 [main ] INFO i.t.datamanager.dao.impl.jpa.ManagedFileEntityTest - 21:48:04.272 [main ] DEBUG i.t.u.spring.jpa.impl.LoggingJpaTransactionManager - Should roll back transaction but cannot - no transaction available 21:48:04.275 [main ] INFO i.t.datamanager.dao.impl.jpa.FingerprintEntityTest - FINISHED TESTS OF it.tidalwave.datamanager.dao.impl.jpa.FingerprintEntityTest [INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 11.809 s - in TestSuite 21:48:04.496 [licationShutdownHook] INFO o.s.orm.jpa.LocalContainerEntityManagerFactoryBean - Closing JPA EntityManagerFactory for persistence unit 'default' 21:48:04.499 [licationShutdownHook] INFO com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Shutdown initiated... 21:48:04.499 [licationShutdownHook] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before shutdown stats (total=10, active=0, idle=10, waiting=0) [INFO] [INFO] Results: [INFO] [INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0 [INFO] [JENKINS] Recording test results [INFO] [INFO] --- jacoco:0.8.9:report (report) @ solidblue3-dao --- [INFO] Loading execution data file /var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao/target/jacoco.exec [INFO] Analyzed bundle 'SolidBlue III :: Dao' with 4 classes [INFO] [INFO] --- jar:3.3.0:jar (default-jar) @ solidblue3-dao --- [INFO] Building jar: /var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao/target/solidblue3-dao-1.1-SNAPSHOT.6332a4670def.jar [INFO] [INFO] --- jar:3.3.0:test-jar (default-test-jar) @ solidblue3-dao --- [INFO] Building jar: /var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao/target/solidblue3-dao-1.1-SNAPSHOT.6332a4670def-tests.jar [INFO] [INFO] --- install:3.1.1:install (default-install) @ solidblue3-dao --- [INFO] Installing /var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao/pom.xml to /var/jenkins_home/jobs/SolidBlue3_Metrics/workspace@tmp/repository/it/tidalwave/solidblue3/solidblue3-dao/1.1-SNAPSHOT/solidblue3-dao-1.1-SNAPSHOT.pom [INFO] Installing /var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao/target/solidblue3-dao-1.1-SNAPSHOT.6332a4670def.jar to /var/jenkins_home/jobs/SolidBlue3_Metrics/workspace@tmp/repository/it/tidalwave/solidblue3/solidblue3-dao/1.1-SNAPSHOT/solidblue3-dao-1.1-SNAPSHOT.jar [INFO] Installing /var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao/target/solidblue3-dao-1.1-SNAPSHOT.6332a4670def-tests.jar to /var/jenkins_home/jobs/SolidBlue3_Metrics/workspace@tmp/repository/it/tidalwave/solidblue3/solidblue3-dao/1.1-SNAPSHOT/solidblue3-dao-1.1-SNAPSHOT-tests.jar [INFO] [INFO] >>> dependency:3.5.0:analyze (default-cli) > test-compile @ solidblue3-dao >>> [INFO] [INFO] --- buildnumber:3.0.0:create (default) @ solidblue3-dao --- [INFO] ShortRevision tag detected. The value is '12'. [INFO] Executing: /bin/sh -c cd '/var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao' && 'git' 'rev-parse' '--verify' '--short=12' 'HEAD' [INFO] Working directory: /var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao [INFO] Storing buildNumber: 6332a4670def at timestamp: 1682804885427 [WARNING] Cannot get the branch information from the git repository: Detecting the current branch failed: fatal: ref HEAD is not a symbolic ref [INFO] ShortRevision tag detected. The value is '12'. [INFO] Executing: /bin/sh -c cd '/var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao' && 'git' 'rev-parse' '--verify' '--short=12' 'HEAD' [INFO] Working directory: /var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao [INFO] Storing buildScmBranch: UNKNOWN [INFO] [INFO] --- enforcer:3.3.0:enforce (enforce-maven) @ solidblue3-dao --- [INFO] Rule 0: org.apache.maven.enforcer.rules.dependency.DependencyConvergence passed [INFO] Rule 3: org.apache.maven.enforcer.rules.RequirePluginVersions passed [INFO] [INFO] --- enforcer:3.3.0:enforce (enforce-banned-dependencies) @ solidblue3-dao --- [INFO] Rule 0: org.apache.maven.enforcer.rules.dependency.BannedDependencies passed [INFO] [INFO] --- antrun:3.1.0:run (generate-and-print-build-description) @ solidblue3-dao --- [INFO] Executing tasks [INFO] [echo] DESCRIPTION: origin/master 1.1-SNAPSHOT 6332a4670def [INFO] [echo] Java Version: 17.0.6 -source 17 -target 17 -release 17 [INFO] Executed tasks [INFO] [INFO] --- jacoco:0.8.9:prepare-agent (prepare-agent) @ solidblue3-dao --- [INFO] tft.test.jacoco.argLine set to -javaagent:/var/jenkins_home/jobs/SolidBlue3_Metrics/workspace@tmp/repository/org/jacoco/org.jacoco.agent/0.8.9/org.jacoco.agent-0.8.9-runtime.jar=destfile=/var/jenkins_home/jobs/SolidBlue3_Metrics/workspace/modules/Dao/target/jacoco.exec [INFO] [INFO] --- resources:3.3.1:resources (default-resources) @ solidblue3-dao --- [INFO] Copying 2 resources from src/main/resources to target/classes [INFO] [INFO] --- compiler:3.11.0:compile (default-compile) @ solidblue3-dao --- [INFO] Changes detected - recompiling the module! :dependency [INFO] Compiling 4 source files with javac [forked debug deprecation release 17] to target/classes [WARNING] No processor claimed any of these annotations: /jakarta.persistence.OrderBy,/jakarta.persistence.Column,/jakarta.persistence.OneToMany,/jakarta.persistence.Table,/jakarta.persistence.Entity,/org.springframework.stereotype.Component,/jakarta.annotation.Nonnull,/org.hibernate.annotations.Cascade,/jakarta.transaction.Transactional,/edu.umd.cs.findbugs.annotations.SuppressFBWarnings,/jakarta.persistence.Id [INFO] [INFO] --- resources:3.3.1:testResources (default-testResources) @ solidblue3-dao --- [INFO] Copying 2 resources from src/test/resources to target/test-classes [INFO] [INFO] --- compiler:3.11.0:testCompile (default-testCompile) @ solidblue3-dao --- [INFO] Changes detected - recompiling the module! :dependency [INFO] Compiling 5 source files with javac [forked debug deprecation release 17] to target/test-classes [WARNING] Failed to getClass for org.apache.maven.plugins.dependency.analyze.AnalyzeMojo [INFO] [INFO] <<< dependency:3.5.0:analyze (default-cli) < test-compile @ solidblue3-dao <<< [INFO] [INFO] [INFO] --- dependency:3.5.0:analyze (default-cli) @ solidblue3-dao --- [WARNING] Used undeclared dependencies found: [WARNING] org.slf4j:slf4j-api:jar:2.0.7:compile [WARNING] org.springframework.boot:spring-boot:jar:3.0.5:compile [WARNING] org.springframework.data:spring-data-jpa:jar:3.0.4:compile [WARNING] org.springframework.boot:spring-boot-autoconfigure:jar:3.0.5:compile [WARNING] org.hamcrest:hamcrest:jar:2.2:test [WARNING] org.springframework:spring-test:jar:6.0.7:test [WARNING] jakarta.persistence:jakarta.persistence-api:jar:3.1.0:compile [WARNING] org.springframework.boot:spring-boot-test-autoconfigure:jar:3.0.5:test [WARNING] org.springframework:spring-orm:jar:6.0.7:compile [WARNING] jakarta.transaction:jakarta.transaction-api:jar:2.0.1:compile [WARNING] org.springframework:spring-context:jar:6.0.7:compile [WARNING] jakarta.inject:jakarta.inject-api:jar:2.0.0:compile [WARNING] org.hibernate.orm:hibernate-core:jar:6.1.7.Final:compile [WARNING] Unused declared dependencies found: [WARNING] org.springframework.boot:spring-boot-starter-data-jpa:jar:3.0.5:compile [WARNING] org.hibernate.orm:hibernate-community-dialects:jar:6.1.7.Final:runtime [WARNING] p6spy:p6spy:jar:3.9.1:runtime [WARNING] org.xerial:sqlite-jdbc:jar:3.41.2.1:runtime [WARNING] org.springframework.boot:spring-boot-starter-test:jar:3.0.5:test [WARNING] org.projectlombok:lombok:jar:1.18.24:provided [WARNING] org.slf4j:jcl-over-slf4j:jar:2.0.7:runtime [WARNING] org.slf4j:jul-to-slf4j:jar:2.0.7:runtime [WARNING] it.tidalwave.thesefoolishthings:it-tidalwave-util-test:jar:3.2-ALPHA-21:test [WARNING] org.hamcrest:hamcrest-all:jar:1.3:test [WARNING] org.mockito:mockito-core:jar:4.8.1:test [WARNING] ch.qos.logback:logback-classic:jar:1.4.7:test [WARNING] Non-test scoped test only dependencies found: [WARNING] org.springframework.boot:spring-boot:jar:3.0.5:compile [WARNING] org.springframework.data:spring-data-jpa:jar:3.0.4:compile [WARNING] org.springframework.boot:spring-boot-autoconfigure:jar:3.0.5:compile [WARNING] org.springframework:spring-orm:jar:6.0.7:compile [WARNING] jakarta.inject:jakarta.inject-api:jar:2.0.0:compile [JENKINS] Archiving disabled Started calculate disk usage of build Finished Calculation of disk usage of build in 0 seconds Started calculate disk usage of workspace Finished Calculation of disk usage of workspace in 0 seconds [JENKINS] Archiving disabled [JENKINS] Archiving disabled [JENKINS] Archiving disabled [JENKINS] Archiving disabled [JENKINS] Archiving disabled [JENKINS] Archiving disabled [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary for SolidBlue III 1.1-SNAPSHOT: [INFO] [INFO] SolidBlue III ...................................... SUCCESS [ 5.171 s] [INFO] SolidBlue III (modules) ............................ SUCCESS [ 1.225 s] [INFO] SolidBlue III :: Commons ........................... SUCCESS [ 5.104 s] [INFO] SolidBlue III :: Model ............................. SUCCESS [ 11.444 s] [INFO] SolidBlue III :: Dao ............................... SUCCESS [ 25.786 s] [INFO] SolidBlue III :: Application no GUI ................ SUCCESS [ 16.604 s] [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 01:12 min [INFO] Finished at: 2023-04-29T21:48:30Z [INFO] ------------------------------------------------------------------------ [WARNING] The requested profile "installers" could not be activated because it does not exist.