Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Reproducer for HHH-18389 #405

Closed
wants to merge 2 commits into from

Upgrade to ORM 6.6.0.CR1

d011e1b
Select commit
Loading
Failed to load commit list.
Closed

Reproducer for HHH-18389 #405

Upgrade to ORM 6.6.0.CR1
d011e1b
Select commit
Loading
Failed to load commit list.
Hibernate Jenkins CI / Jenkins failed Jul 16, 2024 in 30s

Test: error in 'sh' step

Test / Shell Script

Error in sh step, with arguments mvn -B verify.

script returned exit code 1
Build log
+ mvn -B verify
[INFO] Scanning for projects...
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Build Order:
[INFO] 
[INFO] Hibernate ORM 5 Test Case Template                                 [jar]
[INFO] Hibernate ORM 6 Test Case Template                                 [jar]
[INFO] Hibernate Envers 5 Test Case Template                              [jar]
[INFO] Hibernate Envers 6 Test Case Template                              [jar]
[INFO] Hibernate Search 5 with Elasticsearch 2 Test Case Template         [jar]
[INFO] Hibernate Search 5 with Elasticsearch 5 Test Case Template         [jar]
[INFO] Hibernate Search 5 with Lucene Test Case Template                  [jar]
[INFO] Hibernate Search 6 with Hibernate ORM and Elasticsearch Test Case Template [jar]
[INFO] Hibernate Search 6 with Hibernate ORM and Lucene Test Case Template [jar]
[INFO] Hibernate Search 7 with Hibernate ORM and Elasticsearch Test Case Template [jar]
[INFO] Hibernate Search 7 with Hibernate ORM and Lucene Test Case Template [jar]
[INFO] Hibernate Validator 6 Test Case Template                           [jar]
[INFO] Hibernate Validator 8 Test Case Template                           [jar]
[INFO] Hibernate Test Case Templates - Aggregator POM                     [pom]
[INFO] 
[INFO] --< org.hibernate.testcasetemplate:test-case-template-hibernate-orm-5 >--
[INFO] Building Hibernate ORM 5 Test Case Template 1.0.0.Final           [1/14]
[INFO]   from orm/hibernate-orm-5/pom.xml
[INFO] --------------------------------[ jar ]---------------------------------
[INFO] 
[INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ test-case-template-hibernate-orm-5 ---
[WARNING] Using platform encoding (UTF-8 actually) to copy filtered resources, i.e. build is platform dependent!
[INFO] skip non existing resourceDirectory /mnt/workdir/jenkins/workspace/rnate-test-case-templates_PR-405/orm/hibernate-orm-5/src/main/resources
[INFO] 
[INFO] --- maven-compiler-plugin:3.13.0:compile (default-compile) @ test-case-template-hibernate-orm-5 ---
[INFO] No sources to compile
[INFO] 
[INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ test-case-template-hibernate-orm-5 ---
[WARNING] Using platform encoding (UTF-8 actually) to copy filtered resources, i.e. build is platform dependent!
[INFO] Copying 3 resources
[INFO] 
[INFO] --- maven-compiler-plugin:3.13.0:testCompile (default-testCompile) @ test-case-template-hibernate-orm-5 ---
[INFO] Nothing to compile - all classes are up to date.
[INFO] 
[INFO] --- hibernate-enhance-maven-plugin:5.6.15.Final:enhance (default) @ test-case-template-hibernate-orm-5 ---
[WARNING] Skipping Hibernate bytecode enhancement plugin execution since no feature is enabled
[INFO] 
[INFO] --- maven-surefire-plugin:2.12.4:test (default-test) @ test-case-template-hibernate-orm-5 ---
[INFO] Surefire report directory: /mnt/workdir/jenkins/workspace/rnate-test-case-templates_PR-405/orm/hibernate-orm-5/target/surefire-reports
[INFO] Downloading from central: https://repo.maven.apache.org/maven2/org/apache/maven/surefire/surefire-junit4/2.12.4/surefire-junit4-2.12.4.pom
[INFO] Downloaded from central: https://repo.maven.apache.org/maven2/org/apache/maven/surefire/surefire-junit4/2.12.4/surefire-junit4-2.12.4.pom (2.4 kB at 11 kB/s)
[INFO] Downloading from central: https://repo.maven.apache.org/maven2/org/apache/maven/surefire/surefire-providers/2.12.4/surefire-providers-2.12.4.pom
[INFO] Downloaded from central: https://repo.maven.apache.org/maven2/org/apache/maven/surefire/surefire-providers/2.12.4/surefire-providers-2.12.4.pom (2.3 kB at 213 kB/s)
[INFO] Downloading from central: https://repo.maven.apache.org/maven2/org/apache/maven/surefire/surefire-junit4/2.12.4/surefire-junit4-2.12.4.jar
[INFO] Downloaded from central: https://repo.maven.apache.org/maven2/org/apache/maven/surefire/surefire-junit4/2.12.4/surefire-junit4-2.12.4.jar (37 kB at 1.6 MB/s)

-------------------------------------------------------
 T E S T S
-------------------------------------------------------
Running org.hibernate.bugs.JPAUnitTestCase
2024-07-16 08:59:02 INFO  LogHelper:31 - HHH000204: Processing PersistenceUnitInfo [name: templatePU]
2024-07-16 08:59:02 INFO  Version:44 - HHH000412: Hibernate ORM core version 5.6.15.Final
2024-07-16 08:59:02 INFO  Environment:184 - HHH000205: Loaded properties from resource hibernate.properties: {hibernate.format_sql=true, hibernate.dialect=org.hibernate.dialect.H2Dialect, hibernate.connection.username=sa, hibernate.connection.url=jdbc:h2:mem:db1;DB_CLOSE_DELAY=-1, hibernate.max_fetch_depth=5, hibernate.show_sql=false, hibernate.jdbc.batch_versioned_data=true, javax.persistence.validation.mode=NONE, hibernate.connection.driver_class=org.h2.Driver, hibernate.connection.password=****, hibernate.bytecode.use_reflection_optimizer=false, hibernate.cache.region_prefix=hibernate.test, hibernate.connection.pool_size=5, hibernate.cache.region.factory_class=org.hibernate.testing.cache.CachingRegionFactory, hibernate.service.allow_crawling=false, hibernate.session.events.log=true}
2024-07-16 08:59:02 WARN  CachingRegionFactory:48 - org.hibernate.testing.cache.CachingRegionFactory should be only used for testing.
2024-07-16 08:59:03 INFO  Version:56 - HCANN000001: Hibernate Commons Annotations {5.1.2.Final}
2024-07-16 08:59:03 WARN  pooling:75 - HHH10001002: Using Hibernate built-in connection pool (not for production use!)
2024-07-16 08:59:03 INFO  pooling:119 - HHH10001005: using driver [org.h2.Driver] at URL [jdbc:h2:mem:db1;DB_CLOSE_DELAY=-1]
2024-07-16 08:59:03 INFO  pooling:128 - HHH10001001: Connection properties: {password=****, user=sa}
2024-07-16 08:59:03 INFO  pooling:132 - HHH10001003: Autocommit mode: false
2024-07-16 08:59:03 INFO  DriverManagerConnectionProviderImpl:322 - HHH000115: Hibernate connection pool size: 5 (min=1)
2024-07-16 08:59:03 INFO  Dialect:175 - HHH000400: Using dialect: org.hibernate.dialect.H2Dialect
2024-07-16 08:59:03 INFO  JtaPlatformInitiator:52 - HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.JBossStandAloneJtaPlatform]
2024-07-16 08:59:03 INFO  StatisticalLoggingSessionEventListener:258 - Session Metrics {
    55807 nanoseconds spent acquiring 1 JDBC connections;
    133364 nanoseconds spent releasing 1 JDBC connections;
    0 nanoseconds spent preparing 0 JDBC statements;
    0 nanoseconds spent executing 0 JDBC statements;
    0 nanoseconds spent executing 0 JDBC batches;
    0 nanoseconds spent performing 0 L2C puts;
    0 nanoseconds spent performing 0 L2C hits;
    0 nanoseconds spent performing 0 L2C misses;
    0 nanoseconds spent executing 0 flushes (flushing a total of 0 entities and 0 collections);
    0 nanoseconds spent executing 0 partial-flushes (flushing a total of 0 entities and 0 collections)
}
2024-07-16 08:59:03 INFO  SchemaDropperImpl$DelayedDropActionImpl:538 - HHH000477: Starting delayed evictData of schema as part of SessionFactory shut-down'
2024-07-16 08:59:03 INFO  pooling:550 - HHH10001008: Cleaning up connection pool [jdbc:h2:mem:db1;DB_CLOSE_DELAY=-1]
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.46 sec
Running org.hibernate.bugs.ORMStandaloneTestCase
2024-07-16 08:59:03 WARN  CachingRegionFactory:48 - org.hibernate.testing.cache.CachingRegionFactory should be only used for testing.
2024-07-16 08:59:03 WARN  pooling:75 - HHH10001002: Using Hibernate built-in connection pool (not for production use!)
2024-07-16 08:59:03 INFO  pooling:119 - HHH10001005: using driver [org.h2.Driver] at URL [jdbc:h2:mem:db1;DB_CLOSE_DELAY=-1]
2024-07-16 08:59:03 INFO  pooling:128 - HHH10001001: Connection properties: {password=****, user=sa}
2024-07-16 08:59:03 INFO  pooling:132 - HHH10001003: Autocommit mode: false
2024-07-16 08:59:03 INFO  DriverManagerConnectionProviderImpl:322 - HHH000115: Hibernate connection pool size: 5 (min=1)
2024-07-16 08:59:03 INFO  Dialect:175 - HHH000400: Using dialect: org.hibernate.dialect.H2Dialect
2024-07-16 08:59:03 INFO  access:48 - HHH10001501: Connection obtained from JdbcConnectionAccess [org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentInitiator$ConnectionProviderJdbcConnectionAccess@2eed37f4] for (non-JTA) DDL execution was not in auto-commit mode; the Connection 'local transaction' will be committed and the Connection will be set into auto-commit mode.
2024-07-16 08:59:03 INFO  JtaPlatformInitiator:52 - HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.JBossStandAloneJtaPlatform]
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.036 sec
Running org.hibernate.bugs.ORMUnitTestCase
2024-07-16 08:59:03 INFO  Dialect:175 - HHH000400: Using dialect: org.hibernate.dialect.H2Dialect
2024-07-16 08:59:03 INFO  CustomRunner:116 - BeforeClass: org.hibernate.bugs.ORMUnitTestCase
2024-07-16 08:59:03 INFO  Dialect:175 - HHH000400: Using dialect: org.hibernate.dialect.H2Dialect
2024-07-16 08:59:03 WARN  TestClassMetadata:173 - Open SessionFactory instances found prior to start of test class [org.hibernate.bugs.ORMUnitTestCase]
2024-07-16 08:59:03 WARN  CachingRegionFactory:48 - org.hibernate.testing.cache.CachingRegionFactory should be only used for testing.
2024-07-16 08:59:03 WARN  pooling:75 - HHH10001002: Using Hibernate built-in connection pool (not for production use!)
2024-07-16 08:59:03 INFO  pooling:119 - HHH10001005: using driver [org.h2.Driver] at URL [jdbc:h2:mem:db1;DB_CLOSE_DELAY=-1]
2024-07-16 08:59:03 INFO  pooling:128 - HHH10001001: Connection properties: {password=****, user=sa}
2024-07-16 08:59:03 INFO  pooling:132 - HHH10001003: Autocommit mode: false
2024-07-16 08:59:03 INFO  DriverManagerConnectionProviderImpl:322 - HHH000115: Hibernate connection pool size: 5 (min=1)
2024-07-16 08:59:03 INFO  Dialect:175 - HHH000400: Using dialect: org.hibernate.dialect.H2Dialect
2024-07-16 08:59:03 INFO  JtaPlatformInitiator:52 - HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.JBossStandAloneJtaPlatform]
2024-07-16 08:59:03 INFO  CustomRunner:123 - Test: hhh123Test
2024-07-16 08:59:03 INFO  StatisticalLoggingSessionEventListener:258 - Session Metrics {
    44008 nanoseconds spent acquiring 1 JDBC connections;
    81690 nanoseconds spent releasing 1 JDBC connections;
    0 nanoseconds spent preparing 0 JDBC statements;
    0 nanoseconds spent executing 0 JDBC statements;
    0 nanoseconds spent executing 0 JDBC batches;
    0 nanoseconds spent performing 0 L2C puts;
    0 nanoseconds spent performing 0 L2C hits;
    0 nanoseconds spent performing 0 L2C misses;
    0 nanoseconds spent executing 0 flushes (flushing a total of 0 entities and 0 collections);
    0 nanoseconds spent executing 0 partial-flushes (flushing a total of 0 entities and 0 collections)
}
2024-07-16 08:59:03 INFO  SchemaDropperImpl$DelayedDropActionImpl:538 - HHH000477: Starting delayed evictData of schema as part of SessionFactory shut-down'
2024-07-16 08:59:03 WARN  TestClassMetadata:218 - Open SessionFactory instances found after completion of test class [org.hibernate.bugs.ORMUnitTestCase]; closing them
2024-07-16 08:59:03 INFO  pooling:550 - HHH10001008: Cleaning up connection pool [jdbc:h2:mem:db1;DB_CLOSE_DELAY=-1]
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.062 sec

Results :

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

[INFO] 
[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ test-case-template-hibernate-orm-5 ---
[WARNING] JAR will be empty - no content was marked for inclusion!
[INFO] 
[INFO] --< org.hibernate.testcasetemplate:test-case-template-hibernate-orm-6 >--
[INFO] Building Hibernate ORM 6 Test Case Template 1.0.0.Final           [2/14]
[INFO]   from orm/hibernate-orm-6/pom.xml
[INFO] --------------------------------[ jar ]---------------------------------
[INFO] 
[INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ test-case-template-hibernate-orm-6 ---
[WARNING] Using platform encoding (UTF-8 actually) to copy filtered resources, i.e. build is platform dependent!
[INFO] skip non existing resourceDirectory /mnt/workdir/jenkins/workspace/rnate-test-case-templates_PR-405/orm/hibernate-orm-6/src/main/resources
[INFO] 
[INFO] --- maven-compiler-plugin:3.13.0:compile (default-compile) @ test-case-template-hibernate-orm-6 ---
[INFO] No sources to compile
[INFO] 
[INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ test-case-template-hibernate-orm-6 ---
[WARNING] Using platform encoding (UTF-8 actually) to copy filtered resources, i.e. build is platform dependent!
[INFO] Copying 3 resources
[INFO] 
[INFO] --- maven-compiler-plugin:3.13.0:testCompile (default-testCompile) @ test-case-template-hibernate-orm-6 ---
[INFO] Nothing to compile - all classes are up to date.
[INFO] 
[INFO] --- hibernate-enhance-maven-plugin:6.6.0.CR1:enhance (default) @ test-case-template-hibernate-orm-6 ---
[WARNING] Skipping Hibernate bytecode enhancement plugin execution since no feature is enabled
[INFO] 
[INFO] --- maven-surefire-plugin:2.12.4:test (default-test) @ test-case-template-hibernate-orm-6 ---
[INFO] Surefire report directory: /mnt/workdir/jenkins/workspace/rnate-test-case-templates_PR-405/orm/hibernate-orm-6/target/surefire-reports

-------------------------------------------------------
 T E S T S
-------------------------------------------------------
Running org.hibernate.bugs.JPAUnitTestCase
2024-07-16 08:59:04 INFO  LogHelper:31 - HHH000204: Processing PersistenceUnitInfo [name: templatePU]
2024-07-16 08:59:04 INFO  Version:44 - HHH000412: Hibernate ORM core version 6.6.0.CR1
2024-07-16 08:59:04 INFO  Environment:148 - HHH000205: Loaded properties from resource hibernate.properties: {hibernate.format_sql=true, jakarta.persistence.validation.mode=NONE, hibernate.dialect=org.hibernate.dialect.H2Dialect, hibernate.connection.username=sa, hibernate.connection.url=jdbc:h2:mem:db1;DB_CLOSE_DELAY=-1, hibernate.max_fetch_depth=5, hibernate.show_sql=false, hibernate.jdbc.batch_versioned_data=true, hibernate.connection.driver_class=org.h2.Driver, hibernate.connection.password=****, hibernate.cache.region_prefix=hibernate.test, hibernate.connection.pool_size=5, hibernate.cache.region.factory_class=org.hibernate.testing.cache.CachingRegionFactory, hibernate.service.allow_crawling=false, hibernate.session.events.log=true}
2024-07-16 08:59:05 WARN  CachingRegionFactory:48 - org.hibernate.testing.cache.CachingRegionFactory should be only used for testing.
2024-07-16 08:59:05 INFO  RegionFactoryInitiator:53 - HHH000025: Second-level cache region factory [org.hibernate.testing.cache.CachingRegionFactory]
2024-07-16 08:59:05 WARN  pooling:81 - HHH10001002: Using built-in connection pool (not intended for production use)
2024-07-16 08:59:05 INFO  pooling:136 - HHH10001005: Loaded JDBC driver class: org.h2.Driver
2024-07-16 08:59:05 INFO  pooling:158 - HHH10001012: Connecting with JDBC URL [jdbc:h2:mem:db1;DB_CLOSE_DELAY=-1]
2024-07-16 08:59:05 INFO  pooling:167 - HHH10001001: Connection properties: {password=****, user=sa}
2024-07-16 08:59:05 INFO  pooling:171 - HHH10001003: Autocommit mode: false
2024-07-16 08:59:05 INFO  pooling:372 - HHH10001115: Connection pool size: 5 (min=1)
2024-07-16 08:59:05 WARN  deprecation:153 - HHH90000025: H2Dialect does not need to be specified explicitly using 'hibernate.dialect' (remove the property setting and it will be selected by default)
2024-07-16 08:59:06 INFO  JtaPlatformInitiator:56 - HHH000490: Using JTA platform [org.hibernate.engine.transaction.jta.platform.internal.WildFlyStandAloneJtaPlatform]
Hibernate: 
    drop table if exists Child1 cascade 
2024-07-16 08:59:06 INFO  access:52 - HHH10001501: Connection obtained from JdbcConnectionAccess [org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentInitiator$ConnectionProviderJdbcConnectionAccess@455da438] for (non-JTA) DDL execution was not in auto-commit mode; the Connection 'local transaction' will be committed and the Connection will be set into auto-commit mode.
Hibernate: 
    drop table if exists Child2 cascade 
Hibernate: 
    drop table if exists Parent cascade 
Hibernate: 
    create table Child1 (
        id bigint not null,
        parent_id bigint,
        name varchar(255),
        primary key (id)
    )
2024-07-16 08:59:06 INFO  access:52 - HHH10001501: Connection obtained from JdbcConnectionAccess [org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentInitiator$ConnectionProviderJdbcConnectionAccess@770cae59] for (non-JTA) DDL execution was not in auto-commit mode; the Connection 'local transaction' will be committed and the Connection will be set into auto-commit mode.
Hibernate: 
    create table Child2 (
        id bigint not null,
        parent_id bigint,
        name varchar(255),
        primary key (id)
    )
Hibernate: 
    create table Parent (
        id bigint not null,
        name varchar(255),
        primary key (id)
    )
Hibernate: 
    alter table if exists Child1 
       add constraint FKdnj7ch56fyiumxg9ilg8fprha 
       foreign key (parent_id) 
       references Parent
Hibernate: 
    alter table if exists Child2 
       add constraint FK7wsyd8r8p6ll7eagnhcw14xyw 
       foreign key (parent_id) 
       references Parent
2024-07-16 08:59:06 INFO  StatisticalLoggingSessionEventListener:280 - Session Metrics {
    76495 nanoseconds spent acquiring 1 JDBC connections;
    138669 nanoseconds spent releasing 1 JDBC connections;
    0 nanoseconds spent preparing 0 JDBC statements;
    0 nanoseconds spent executing 0 JDBC statements;
    0 nanoseconds spent executing 0 JDBC batches;
    0 nanoseconds spent performing 0 L2C puts;
    0 nanoseconds spent performing 0 L2C hits;
    0 nanoseconds spent performing 0 L2C misses;
    0 nanoseconds spent executing 0 flushes (flushing a total of 0 entities and 0 collections);
    0 nanoseconds spent executing 0 pre-partial-flushes;
    0 nanoseconds spent executing 0 partial-flushes (flushing a total of 0 entities and 0 collections)
}
Hibernate: 
    drop table if exists Child1 cascade 
2024-07-16 08:59:06 INFO  access:52 - HHH10001501: Connection obtained from JdbcConnectionAccess [org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentInitiator$ConnectionProviderJdbcConnectionAccess@7f2c995b] for (non-JTA) DDL execution was not in auto-commit mode; the Connection 'local transaction' will be committed and the Connection will be set into auto-commit mode.
Hibernate: 
    drop table if exists Child2 cascade 
Hibernate: 
    drop table if exists Parent cascade 
2024-07-16 08:59:06 INFO  pooling:607 - HHH10001008: Cleaning up connection pool [jdbc:h2:mem:db1;DB_CLOSE_DELAY=-1]
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.527 sec
Running org.hibernate.bugs.ORMStandaloneTestCase
2024-07-16 08:59:06 WARN  CachingRegionFactory:48 - org.hibernate.testing.cache.CachingRegionFactory should be only used for testing.
2024-07-16 08:59:06 INFO  RegionFactoryInitiator:53 - HHH000025: Second-level cache region factory [org.hibernate.testing.cache.CachingRegionFactory]
2024-07-16 08:59:06 WARN  pooling:81 - HHH10001002: Using built-in connection pool (not intended for production use)
2024-07-16 08:59:06 INFO  pooling:136 - HHH10001005: Loaded JDBC driver class: org.h2.Driver
2024-07-16 08:59:06 INFO  pooling:158 - HHH10001012: Connecting with JDBC URL [jdbc:h2:mem:db1;DB_CLOSE_DELAY=-1]
2024-07-16 08:59:06 INFO  pooling:167 - HHH10001001: Connection properties: {password=****, user=sa}
2024-07-16 08:59:06 INFO  pooling:171 - HHH10001003: Autocommit mode: false
2024-07-16 08:59:06 INFO  pooling:372 - HHH10001115: Connection pool size: 5 (min=1)
2024-07-16 08:59:06 WARN  deprecation:153 - HHH90000025: H2Dialect does not need to be specified explicitly using 'hibernate.dialect' (remove the property setting and it will be selected by default)
2024-07-16 08:59:06 INFO  JtaPlatformInitiator:56 - HHH000490: Using JTA platform [org.hibernate.engine.transaction.jta.platform.internal.WildFlyStandAloneJtaPlatform]
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.055 sec
Running org.hibernate.bugs.ORMUnitTestCase
2024-07-16 08:59:06 INFO  CustomRunner:126 - BeforeClass: org.hibernate.bugs.ORMUnitTestCase
2024-07-16 08:59:06 WARN  resources:169 - Open SessionFactory instances found prior to start of test class [org.hibernate.bugs.ORMUnitTestCase]
2024-07-16 08:59:06 WARN  CachingRegionFactory:48 - org.hibernate.testing.cache.CachingRegionFactory should be only used for testing.
2024-07-16 08:59:06 INFO  RegionFactoryInitiator:53 - HHH000025: Second-level cache region factory [org.hibernate.testing.cache.CachingRegionFactory]
2024-07-16 08:59:06 WARN  pooling:81 - HHH10001002: Using built-in connection pool (not intended for production use)
2024-07-16 08:59:06 INFO  pooling:136 - HHH10001005: Loaded JDBC driver class: org.h2.Driver
2024-07-16 08:59:06 INFO  pooling:158 - HHH10001012: Connecting with JDBC URL [jdbc:h2:mem:db1;DB_CLOSE_DELAY=-1]
2024-07-16 08:59:06 INFO  pooling:167 - HHH10001001: Connection properties: {password=****, user=sa}
2024-07-16 08:59:06 INFO  pooling:171 - HHH10001003: Autocommit mode: false
2024-07-16 08:59:06 INFO  pooling:372 - HHH10001115: Connection pool size: 5 (min=1)
2024-07-16 08:59:06 WARN  deprecation:153 - HHH90000025: H2Dialect does not need to be specified explicitly using 'hibernate.dialect' (remove the property setting and it will be selected by default)
2024-07-16 08:59:06 INFO  JtaPlatformInitiator:56 - HHH000490: Using JTA platform [org.hibernate.engine.transaction.jta.platform.internal.WildFlyStandAloneJtaPlatform]
Hibernate: 
    drop table if exists Child1 cascade 
2024-07-16 08:59:06 INFO  access:52 - HHH10001501: Connection obtained from JdbcConnectionAccess [org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentInitiator$ConnectionProviderJdbcConnectionAccess@6a2badb1] for (non-JTA) DDL execution was not in auto-commit mode; the Connection 'local transaction' will be committed and the Connection will be set into auto-commit mode.
Hibernate: 
    drop table if exists Child2 cascade 
Hibernate: 
    drop table if exists Parent cascade 
Hibernate: 
    create table Child1 (
        id bigint not null,
        parent_id bigint,
        name varchar(255),
        primary key (id)
    )
2024-07-16 08:59:06 INFO  access:52 - HHH10001501: Connection obtained from JdbcConnectionAccess [org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentInitiator$ConnectionProviderJdbcConnectionAccess@14201a90] for (non-JTA) DDL execution was not in auto-commit mode; the Connection 'local transaction' will be committed and the Connection will be set into auto-commit mode.
Hibernate: 
    create table Child2 (
        id bigint not null,
        parent_id bigint,
        name varchar(255),
        primary key (id)
    )
Hibernate: 
    create table Parent (
        id bigint not null,
        name varchar(255),
        primary key (id)
    )
Hibernate: 
    alter table if exists Child1 
       add constraint FKdnj7ch56fyiumxg9ilg8fprha 
       foreign key (parent_id) 
       references Parent
Hibernate: 
    alter table if exists Child2 
       add constraint FK7wsyd8r8p6ll7eagnhcw14xyw 
       foreign key (parent_id) 
       references Parent
2024-07-16 08:59:07 INFO  CustomRunner:133 - Test: hhh123Test
Hibernate: 
    insert 
    into
        Parent
        (name, id) 
    values
        (?, ?)
2024-07-16 08:59:07 INFO  StatisticalLoggingSessionEventListener:280 - Session Metrics {
    41007 nanoseconds spent acquiring 1 JDBC connections;
    81927 nanoseconds spent releasing 1 JDBC connections;
    4074346 nanoseconds spent preparing 1 JDBC statements;
    1608149 nanoseconds spent executing 1 JDBC statements;
    0 nanoseconds spent executing 0 JDBC batches;
    0 nanoseconds spent performing 0 L2C puts;
    0 nanoseconds spent performing 0 L2C hits;
    0 nanoseconds spent performing 0 L2C misses;
    40015139 nanoseconds spent executing 1 flushes (flushing a total of 1 entities and 0 collections);
    0 nanoseconds spent executing 0 pre-partial-flushes;
    0 nanoseconds spent executing 0 partial-flushes (flushing a total of 0 entities and 0 collections)
}
Hibernate: 
    select
        p1_0.id,
        p1_0.name 
    from
        Parent p1_0 
    where
        p1_0.id=?
2024-07-16 08:59:07 INFO  StatisticalLoggingSessionEventListener:280 - Session Metrics {
    60874 nanoseconds spent acquiring 1 JDBC connections;
    64744 nanoseconds spent releasing 1 JDBC connections;
    402580 nanoseconds spent preparing 1 JDBC statements;
    4456750 nanoseconds spent executing 1 JDBC statements;
    0 nanoseconds spent executing 0 JDBC batches;
    0 nanoseconds spent performing 0 L2C puts;
    0 nanoseconds spent performing 0 L2C hits;
    0 nanoseconds spent performing 0 L2C misses;
    2154064 nanoseconds spent executing 1 flushes (flushing a total of 0 entities and 0 collections);
    0 nanoseconds spent executing 0 pre-partial-flushes;
    0 nanoseconds spent executing 0 partial-flushes (flushing a total of 0 entities and 0 collections)
}
Hibernate: 
    drop table if exists Child1 cascade 
2024-07-16 08:59:07 INFO  access:52 - HHH10001501: Connection obtained from JdbcConnectionAccess [org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentInitiator$ConnectionProviderJdbcConnectionAccess@29f95272] for (non-JTA) DDL execution was not in auto-commit mode; the Connection 'local transaction' will be committed and the Connection will be set into auto-commit mode.
Hibernate: 
    drop table if exists Child2 cascade 
Hibernate: 
    drop table if exists Parent cascade 
2024-07-16 08:59:07 WARN  CachingRegionFactory:48 - org.hibernate.testing.cache.CachingRegionFactory should be only used for testing.
2024-07-16 08:59:07 INFO  RegionFactoryInitiator:53 - HHH000025: Second-level cache region factory [org.hibernate.testing.cache.CachingRegionFactory]
2024-07-16 08:59:07 WARN  deprecation:153 - HHH90000025: H2Dialect does not need to be specified explicitly using 'hibernate.dialect' (remove the property setting and it will be selected by default)
2024-07-16 08:59:07 INFO  JtaPlatformInitiator:56 - HHH000490: Using JTA platform [org.hibernate.engine.transaction.jta.platform.internal.WildFlyStandAloneJtaPlatform]
Hibernate: 
    drop table if exists Child1 cascade 
2024-07-16 08:59:07 INFO  access:52 - HHH10001501: Connection obtained from JdbcConnectionAccess [org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentInitiator$ConnectionProviderJdbcConnectionAccess@2efcc0b3] for (non-JTA) DDL execution was not in auto-commit mode; the Connection 'local transaction' will be committed and the Connection will be set into auto-commit mode.
Hibernate: 
    drop table if exists Child2 cascade 
Hibernate: 
    drop table if exists Parent cascade 
Hibernate: 
    create table Child1 (
        id bigint not null,
        parent_id bigint,
        name varchar(255),
        primary key (id)
    )
2024-07-16 08:59:07 INFO  access:52 - HHH10001501: Connection obtained from JdbcConnectionAccess [org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentInitiator$ConnectionProviderJdbcConnectionAccess@4a453f8d] for (non-JTA) DDL execution was not in auto-commit mode; the Connection 'local transaction' will be committed and the Connection will be set into auto-commit mode.
Hibernate: 
    create table Child2 (
        id bigint not null,
        parent_id bigint,
        name varchar(255),
        primary key (id)
    )
Hibernate: 
    create table Parent (
        id bigint not null,
        name varchar(255),
        primary key (id)
    )
Hibernate: 
    alter table if exists Child1 
       add constraint FKdnj7ch56fyiumxg9ilg8fprha 
       foreign key (parent_id) 
       references Parent
Hibernate: 
    alter table if exists Child2 
       add constraint FK7wsyd8r8p6ll7eagnhcw14xyw 
       foreign key (parent_id) 
       references Parent
Hibernate: 
    drop table if exists Child1 cascade 
2024-07-16 08:59:07 INFO  access:52 - HHH10001501: Connection obtained from JdbcConnectionAccess [org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentInitiator$ConnectionProviderJdbcConnectionAccess@431babe6] for (non-JTA) DDL execution was not in auto-commit mode; the Connection 'local transaction' will be committed and the Connection will be set into auto-commit mode.
Hibernate: 
    drop table if exists Child2 cascade 
Hibernate: 
    drop table if exists Parent cascade 
2024-07-16 08:59:07 WARN  resources:214 - Open SessionFactory instances found after completion of test class [org.hibernate.bugs.ORMUnitTestCase]; closing them
2024-07-16 08:59:07 INFO  pooling:607 - HHH10001008: Cleaning up connection pool [jdbc:h2:mem:db1;DB_CLOSE_DELAY=-1]
Tests run: 1, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.42 sec <<< FAILURE!
hhh123Test(org.hibernate.bugs.ORMUnitTestCase)  Time elapsed: 0.281 sec  <<< ERROR!
org.hibernate.HibernateException: A collection with cascade="all-delete-orphan" was no longer referenced by the owning entity instance: org.hibernate.bugs.Parent.child1
	at org.hibernate.engine.internal.Collections.processDereferencedCollection(Collections.java:98)
	at org.hibernate.engine.internal.Collections.processUnreachableCollection(Collections.java:49)
	at org.hibernate.event.internal.AbstractFlushingEventListener.lambda$flushCollections$1(AbstractFlushingEventListener.java:305)
	at org.hibernate.engine.internal.StatefulPersistenceContext.forEachCollectionEntry(StatefulPersistenceContext.java:1345)
	at org.hibernate.event.internal.AbstractFlushingEventListener.flushCollections(AbstractFlushingEventListener.java:302)
	at org.hibernate.event.internal.AbstractFlushingEventListener.flushEverythingToExecutions(AbstractFlushingEventListener.java:88)
	at org.hibernate.event.internal.AbstractFlushingEventListener.flushEverythingToExecutions(AbstractFlushingEventListener.java:81)
	at org.hibernate.event.internal.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:40)
	at org.hibernate.event.service.internal.EventListenerGroupImpl.fireEventOnEachListener(EventListenerGroupImpl.java:127)
	at org.hibernate.internal.SessionImpl.doFlush(SessionImpl.java:1429)
	at org.hibernate.internal.SessionImpl.managedFlush(SessionImpl.java:491)
	at org.hibernate.internal.SessionImpl.flushBeforeTransactionCompletion(SessionImpl.java:2354)
	at org.hibernate.internal.SessionImpl.beforeTransactionCompletion(SessionImpl.java:1978)
	at org.hibernate.engine.jdbc.internal.JdbcCoordinatorImpl.beforeTransactionCompletion(JdbcCoordinatorImpl.java:439)
	at org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorImpl.beforeCompletionCallback(JdbcResourceLocalTransactionCoordinatorImpl.java:169)
	at org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorImpl$TransactionDriverControlImpl.commit(JdbcResourceLocalTransactionCoordinatorImpl.java:267)
	at org.hibernate.engine.transaction.internal.TransactionImpl.commit(TransactionImpl.java:101)
	at org.hibernate.testing.transaction.TransactionUtil2.inTransaction(TransactionUtil2.java:128)
	at org.hibernate.testing.transaction.TransactionUtil2.lambda$inTransaction$0(TransactionUtil2.java:76)
	at org.hibernate.testing.transaction.TransactionUtil2.inSession(TransactionUtil2.java:35)
	at org.hibernate.testing.transaction.TransactionUtil2.inTransaction(TransactionUtil2.java:74)
	at org.hibernate.testing.junit4.BaseCoreFunctionalTestCase.inTransaction(BaseCoreFunctionalTestCase.java:538)
	at org.hibernate.bugs.ORMUnitTestCase.hhh123Test(ORMUnitTestCase.java:82)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
	at org.hibernate.testing.junit4.ExtendedFrameworkMethod.invokeExplosively(ExtendedFrameworkMethod.java:45)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:299)
	at org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:293)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
	at java.base/java.lang.Thread.run(Thread.java:829)

Running org.hibernate.bugs.QuarkusLikeORMUnitTestCase
2024-07-16 08:59:07 INFO  CustomRunner:126 - BeforeClass: org.hibernate.bugs.QuarkusLikeORMUnitTestCase
2024-07-16 08:59:07 WARN  CachingRegionFactory:48 - org.hibernate.testing.cache.CachingRegionFactory should be only used for testing.
2024-07-16 08:59:07 INFO  RegionFactoryInitiator:53 - HHH000025: Second-level cache region factory [org.hibernate.testing.cache.CachingRegionFactory]
2024-07-16 08:59:07 WARN  deprecation:153 - HHH90000025: H2Dialect does not need to be specified explicitly using 'hibernate.dialect' (remove the property setting and it will be selected by default)
2024-07-16 08:59:07 INFO  JtaPlatformInitiator:56 - HHH000490: Using JTA platform [org.hibernate.engine.transaction.jta.platform.internal.WildFlyStandAloneJtaPlatform]
2024-07-16 08:59:07 INFO  CustomRunner:133 - Test: hhh123Test
2024-07-16 08:59:07 INFO  StatisticalLoggingSessionEventListener:280 - Session Metrics {
    0 nanoseconds spent acquiring 0 JDBC connections;
    0 nanoseconds spent releasing 0 JDBC connections;
    0 nanoseconds spent preparing 0 JDBC statements;
    0 nanoseconds spent executing 0 JDBC statements;
    0 nanoseconds spent executing 0 JDBC batches;
    0 nanoseconds spent performing 0 L2C puts;
    0 nanoseconds spent performing 0 L2C hits;
    0 nanoseconds spent performing 0 L2C misses;
    0 nanoseconds spent executing 0 flushes (flushing a total of 0 entities and 0 collections);
    0 nanoseconds spent executing 0 pre-partial-flushes;
    0 nanoseconds spent executing 0 partial-flushes (flushing a total of 0 entities and 0 collections)
}
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.157 sec

Results :

Tests in error: 
  hhh123Test(org.hibernate.bugs.ORMUnitTestCase): A collection with cascade="all-delete-orphan" was no longer referenced by the owning entity instance: org.hibernate.bugs.Parent.child1

Tests run: 4, Failures: 0, Errors: 1, Skipped: 0

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Hibernate ORM 5 Test Case Template 1.0.0.Final ..... SUCCESS [  3.478 s]
[INFO] Hibernate ORM 6 Test Case Template 1.0.0.Final ..... FAILURE [  3.596 s]
[INFO] Hibernate Envers 5 Test Case Template 1.0.0.Final .. SKIPPED
[INFO] Hibernate Envers 6 Test Case Template 1.0.0.Final .. SKIPPED
[INFO] Hibernate Search 5 with Elasticsearch 2 Test Case Template 1.0.0.Final SKIPPED
[INFO] Hibernate Search 5 with Elasticsearch 5 Test Case Template 1.0.0.Final SKIPPED
[INFO] Hibernate Search 5 with Lucene Test Case Template 1.0.0.Final SKIPPED
[INFO] Hibernate Search 6 with Hibernate ORM and Elasticsearch Test Case Template 1.0.0.Final SKIPPED
[INFO] Hibernate Search 6 with Hibernate ORM and Lucene Test Case Template 1.0.0.Final SKIPPED
[INFO] Hibernate Search 7 with Hibernate ORM and Elasticsearch Test Case Template 1.0.0.Final SKIPPED
[INFO] Hibernate Search 7 with Hibernate ORM and Lucene Test Case Template 1.0.0.Final SKIPPED
[INFO] Hibernate Validator 6 Test Case Template 1.0.0.Final SKIPPED
[INFO] Hibernate Validator 8 Test Case Template 1.0.0.Final SKIPPED
[INFO] Hibernate Test Case Templates - Aggregator POM 1.0-SNAPSHOT SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time:  7.214 s
[INFO] Finished at: 2024-07-16T08:59:07Z
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4:test (default-test) on project test-case-template-hibernate-orm-6: There are test failures.
[ERROR] 
[ERROR] Please refer to /mnt/workdir/jenkins/workspace/rnate-test-case-templates_PR-405/orm/hibernate-orm-6/target/surefire-reports for the individual test results.
[ERROR] -> [Help 1]
[ERROR] 
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR] 
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
[ERROR] 
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR]   mvn <args> -rf :test-case-template-hibernate-orm-6

Details

  • Declarative: Checkout SCM (2.5 sec)
    • Declarative: Tool Install (1.2 sec)
      • Build (16 sec)
      • Test (9.6 sec)
        Error: script returned exit code 1