Spring Data: `quarkus.hibernate-orm.sql-load-script` is run twice after 47338 PR got merged

[michalvavrik]

Describe the bug

I use quarkus.hibernate-orm.sql-load-script=import.sql in my Spring Data application and it seems that when #47338 got merged, the script is run more than once, otherwise I don't understand the error messages.

Expected behavior

Works as before.

Actual behavior

I can see logged:

19:59:19,688 INFO  [app] 19:59:18,915 GenerationTarget encountered exception accepting command : Error executing DDL "INSERT INTO address(id, zipCode) VALUES (1, '28080')" via JDBC [ERROR: duplicate key value violates unique constraint "address_pkey"
19:59:19,688 INFO  [app]   Detail: Key (id)=(1) already exists.]: org.hibernate.tool.schema.spi.CommandAcceptanceException: Error executing DDL "INSERT INTO address(id, zipCode) VALUES (1, '28080')" via JDBC [ERROR: duplicate key value violates unique constraint "address_pkey"
19:59:19,688 INFO  [app]   Detail: Key (id)=(1) already exists.]
19:59:19,688 INFO  [app] 	at org.hibernate.tool.schema.internal.exec.GenerationTargetToDatabase.accept(GenerationTargetToDatabase.java:93)
19:59:19,689 INFO  [app] 	at org.hibernate.tool.schema.internal.Helper.applySqlString(Helper.java:221)
19:59:19,689 INFO  [app] 	at org.hibernate.tool.schema.internal.Helper.applyScript(Helper.java:244)
19:59:19,689 INFO  [app] 	at org.hibernate.tool.schema.internal.AbstractSchemaPopulator.applyImportFiles(AbstractSchemaPopulator.java:150)
19:59:19,689 INFO  [app] 	at org.hibernate.tool.schema.internal.AbstractSchemaPopulator.applyImportSources(AbstractSchemaPopulator.java:58)
19:59:19,689 INFO  [app] 	at org.hibernate.tool.schema.internal.SchemaCreatorImpl.performCreation(SchemaCreatorImpl.java:172)
19:59:19,689 INFO  [app] 	at org.hibernate.tool.schema.internal.SchemaCreatorImpl.doCreation(SchemaCreatorImpl.java:130)
19:59:19,690 INFO  [app] 	at org.hibernate.tool.schema.internal.SchemaCreatorImpl.doCreation(SchemaCreatorImpl.java:106)
19:59:19,690 INFO  [app] 	at org.hibernate.tool.schema.spi.SchemaManagementToolCoordinator.performDatabaseAction(SchemaManagementToolCoordinator.java:237)
19:59:19,690 INFO  [app] 	at org.hibernate.tool.schema.spi.SchemaManagementToolCoordinator.lambda$process$5(SchemaManagementToolCoordinator.java:142)
19:59:19,690 INFO  [app] 	at java.base/java.util.HashMap.forEach(HashMap.java:1429)
19:59:19,690 INFO  [app] 	at org.hibernate.tool.schema.spi.SchemaManagementToolCoordinator.process(SchemaManagementToolCoordinator.java:139)
19:59:19,691 INFO  [app] 	at io.quarkus.hibernate.orm.runtime.observers.SessionFactoryObserverForSchemaExport.sessionFactoryCreated(SessionFactoryObserverForSchemaExport.java:21)
19:59:19,691 INFO  [app] 	at org.hibernate.internal.SessionFactoryObserverChain.sessionFactoryCreated(SessionFactoryObserverChain.java:33)
19:59:19,691 INFO  [app] 	at org.hibernate.internal.SessionFactoryImpl.<init>(SessionFactoryImpl.java:350)
19:59:19,691 INFO  [app] 	at io.quarkus.hibernate.orm.runtime.boot.FastBootEntityManagerFactoryBuilder.build(FastBootEntityManagerFactoryBuilder.java:92)
19:59:19,691 INFO  [app] 	at io.quarkus.hibernate.orm.runtime.FastBootHibernatePersistenceProvider.createEntityManagerFactory(FastBootHibernatePersistenceProvider.java:74)
19:59:19,692 INFO  [app] 	at jakarta.persistence.Persistence.createEntityManagerFactory(Persistence.java:90)
19:59:19,692 INFO  [app] 	at jakarta.persistence.Persistence.createEntityManagerFactory(Persistence.java:66)
19:59:19,693 INFO  [app] 	at io.quarkus.hibernate.orm.runtime.JPAConfig$LazyPersistenceUnit.get(JPAConfig.java:165)
19:59:19,694 INFO  [app] 	at io.quarkus.hibernate.orm.runtime.JPAConfig$1.run(JPAConfig.java:61)
19:59:19,694 INFO  [app] 	at java.base/java.lang.Thread.run(Thread.java:1583)

How to Reproduce?

Reproducer:

1. git clone git@github.com:quarkus-qe/quarkus-test-suite.git
2. cd quarkus-test-suite/spring/spring-data
3. mvn clean verify -Dit.test=PagingAndSortingRepositoryRestResourceIT#create

Output of uname -a or ver

Fedora 41, Ubuntu latest

Output of java -version

Temurin 21 and 17

Quarkus version or git rev

999-SNAPSHOT

Build tool (ie. output of mvnw --version or gradlew --version)

Maven 3.9.9

Additional information

No response

[quarkus-bot]

/cc @aureamunoz (spring), @geoand (spring), @gsmet (hibernate-orm)

[geoand]

cc @yrodiere

[geoand]

Does this issue only happen when Spring Data is used or is something similar observed with just the Hibernate extension?

[michalvavrik]

Does this issue only happen when Spring Data is used or is something similar observed with just the Hibernate extension?

I saw geoand@7240668 so you already have the answer I guess.

[geoand]

I am not sure if this is an issue with the Hibernate extension, or how it is actually being used in the TestSuite.
What I do know for sure is that it has nothing to do with Spring Data.

I have opened #49087 to help the Hibernate folks debug this. That test doesn't fail, but you can see:

2025-07-24 11:52:41,643 WARN  [org.hib.too.sch.int.ExceptionHandlerLoggedImpl] (JPA Startup Thread: users) GenerationTarget encountered exception accepting command : Error executing DDL "INSERT INTO SharedEntity(id, name) VALUES(1, 'default sql load script entity')" via JDBC [Unique index or primary key
violation: "PRIMARY KEY ON PUBLIC.SHAREDENTITY(ID) ( /* key:1 */ CAST(1 AS BIGINT), 'default sql load script entity')";]: org.hibernate.tool.schema.spi.CommandAcceptanceException: Error executing DDL "INSERT INTO SharedEntity(id, name) VALUES(1, 'default sql load script entity')" via JDBC [Unique index or primary key violation: "PRIMARY KEY ON PUBLIC.SHAREDENTITY(ID) ( /* key:1 */ CAST(1 AS BIGINT), 'default sql load script entity')";]
        at org.hibernate.tool.schema.internal.exec.GenerationTargetToDatabase.accept(GenerationTargetToDatabase.java:93)
        at org.hibernate.tool.schema.internal.Helper.applySqlString(Helper.java:221)
        at org.hibernate.tool.schema.internal.Helper.applyScript(Helper.java:244)
        at org.hibernate.tool.schema.internal.AbstractSchemaPopulator.applyImportFiles(AbstractSchemaPopulator.java:150)
        at org.hibernate.tool.schema.internal.AbstractSchemaPopulator.applyImportSources(AbstractSchemaPopulator.java:58)
        at org.hibernate.tool.schema.internal.SchemaCreatorImpl.performCreation(SchemaCreatorImpl.java:172)
        at org.hibernate.tool.schema.internal.SchemaCreatorImpl.doCreation(SchemaCreatorImpl.java:130)
        at org.hibernate.tool.schema.internal.SchemaCreatorImpl.doCreation(SchemaCreatorImpl.java:106)
        at org.hibernate.tool.schema.spi.SchemaManagementToolCoordinator.performDatabaseAction(SchemaManagementToolCoordinator.java:237)
        at org.hibernate.tool.schema.spi.SchemaManagementToolCoordinator.lambda$process$5(SchemaManagementToolCoordinator.java:142)
        at java.base/java.util.HashMap.forEach(HashMap.java:1429)
        at org.hibernate.tool.schema.spi.SchemaManagementToolCoordinator.process(SchemaManagementToolCoordinator.java:139)
        at io.quarkus.hibernate.orm.runtime.observers.SessionFactoryObserverForSchemaExport.sessionFactoryCreated(SessionFactoryObserverForSchemaExport.java:21)
        at org.hibernate.internal.SessionFactoryObserverChain.sessionFactoryCreated(SessionFactoryObserverChain.java:33)
        at org.hibernate.internal.SessionFactoryImpl.<init>(SessionFactoryImpl.java:350)
        at io.quarkus.hibernate.orm.runtime.boot.FastBootEntityManagerFactoryBuilder.build(FastBootEntityManagerFactoryBuilder.java:92)
        at io.quarkus.hibernate.orm.runtime.FastBootHibernatePersistenceProvider.createEntityManagerFactory(FastBootHibernatePersistenceProvider.java:74)
        at jakarta.persistence.Persistence.createEntityManagerFactory(Persistence.java:90)
        at jakarta.persistence.Persistence.createEntityManagerFactory(Persistence.java:66)
        at io.quarkus.hibernate.orm.runtime.JPAConfig$LazyPersistenceUnit.get(JPAConfig.java:165)
        at io.quarkus.hibernate.orm.runtime.JPAConfig$1.run(JPAConfig.java:61)
        at java.base/java.lang.Thread.run(Thread.java:1583)
Caused by: org.h2.jdbc.JdbcSQLIntegrityConstraintViolationException: Unique index or primary key violation: "PRIMARY KEY ON PUBLIC.SHAREDENTITY(ID) ( /* key:1 */ CAST(1 AS BIGINT), 'default sql load script entity')"; SQL statement:
INSERT INTO SharedEntity(id, name) VALUES(1, 'default sql load script entity') [23505-230]
        at org.h2.message.DbException.getJdbcSQLException(DbException.java:520)
        at org.h2.message.DbException.getJdbcSQLException(DbException.java:489)
        at org.h2.message.DbException.get(DbException.java:223)
        at org.h2.message.DbException.get(DbException.java:199)
        at org.h2.mvstore.db.MVPrimaryIndex.add(MVPrimaryIndex.java:121)
        at org.h2.mvstore.db.MVTable.addRow(MVTable.java:518)
        at org.h2.command.dml.Insert.insertRows(Insert.java:174)
        at org.h2.command.dml.Insert.update(Insert.java:135)
        at org.h2.command.dml.DataChangeStatement.update(DataChangeStatement.java:74)
        at org.h2.command.CommandContainer.update(CommandContainer.java:139)
        at org.h2.command.Command.executeUpdate(Command.java:304)
        at org.h2.command.Command.executeUpdate(Command.java:248)
        at org.h2.server.TcpServerThread.process(TcpServerThread.java:386)
        at org.h2.server.TcpServerThread.run(TcpServerThread.java:193)
        at java.base/java.lang.Thread.run(Thread.java:1583)

        at org.h2.message.DbException.getJdbcSQLException(DbException.java:520)
        at org.h2.engine.SessionRemote.readSQLException(SessionRemote.java:663)
        at org.h2.engine.SessionRemote.readException(SessionRemote.java:646)
        at org.h2.engine.SessionRemote.done(SessionRemote.java:620)
        at org.h2.command.CommandRemote.executeUpdate(CommandRemote.java:225)
        at org.h2.jdbc.JdbcStatement.executeInternal(JdbcStatement.java:262)
        at org.h2.jdbc.JdbcStatement.execute(JdbcStatement.java:231)
        at io.agroal.pool.wrapper.StatementWrapper.execute(StatementWrapper.java:220)
        at org.hibernate.tool.schema.internal.exec.GenerationTargetToDatabase.accept(GenerationTargetToDatabase.java:79)
        ... 21 more

2025-07-24 11:52:41,644 WARN  [org.hib.too.sch.int.ExceptionHandlerLoggedImpl] (JPA Startup Thread: users) GenerationTarget encountered exception accepting command : Error executing DDL "INSERT INTO SharedEntity(id, name) VALUES(2, 'import.sql load script entity')" via JDBC [Unique index or primary key violation: "PRIMARY KEY ON PUBLIC.SHAREDENTITY(ID) ( /* key:2 */ CAST(2 AS BIGINT), 'import.sql load script entity')";]: org.hibernate.tool.schema.spi.CommandAcceptanceException: Error executing DDL "INSERT INTO SharedEntity(id, name) VALUES(2, 'import.sql load script entity')" via JDBC [Unique index or primary key violation: "PRIMARY KEY ON PUBLIC.SHAREDENTITY(ID) ( /* key:2 */ CAST(2 AS BIGINT), 'import.sql load script entity')";]
        at org.hibernate.tool.schema.internal.exec.GenerationTargetToDatabase.accept(GenerationTargetToDatabase.java:93)
        at org.hibernate.tool.schema.internal.Helper.applySqlString(Helper.java:221)
        at org.hibernate.tool.schema.internal.Helper.applyScript(Helper.java:244)
        at org.hibernate.tool.schema.internal.AbstractSchemaPopulator.applyImportFiles(AbstractSchemaPopulator.java:150)
        at org.hibernate.tool.schema.internal.AbstractSchemaPopulator.applyImportSources(AbstractSchemaPopulator.java:58)
        at org.hibernate.tool.schema.internal.SchemaCreatorImpl.performCreation(SchemaCreatorImpl.java:172)
        at org.hibernate.tool.schema.internal.SchemaCreatorImpl.doCreation(SchemaCreatorImpl.java:130)
        at org.hibernate.tool.schema.internal.SchemaCreatorImpl.doCreation(SchemaCreatorImpl.java:106)
        at org.hibernate.tool.schema.spi.SchemaManagementToolCoordinator.performDatabaseAction(SchemaManagementToolCoordinator.java:237)
        at org.hibernate.tool.schema.spi.SchemaManagementToolCoordinator.lambda$process$5(SchemaManagementToolCoordinator.java:142)
        at java.base/java.util.HashMap.forEach(HashMap.java:1429)
        at org.hibernate.tool.schema.spi.SchemaManagementToolCoordinator.process(SchemaManagementToolCoordinator.java:139)
        at io.quarkus.hibernate.orm.runtime.observers.SessionFactoryObserverForSchemaExport.sessionFactoryCreated(SessionFactoryObserverForSchemaExport.java:21)
        at org.hibernate.internal.SessionFactoryObserverChain.sessionFactoryCreated(SessionFactoryObserverChain.java:33)
        at org.hibernate.internal.SessionFactoryImpl.<init>(SessionFactoryImpl.java:350)
        at io.quarkus.hibernate.orm.runtime.boot.FastBootEntityManagerFactoryBuilder.build(FastBootEntityManagerFactoryBuilder.java:92)
        at io.quarkus.hibernate.orm.runtime.FastBootHibernatePersistenceProvider.createEntityManagerFactory(FastBootHibernatePersistenceProvider.java:74)
        at jakarta.persistence.Persistence.createEntityManagerFactory(Persistence.java:90)
        at jakarta.persistence.Persistence.createEntityManagerFactory(Persistence.java:66)
        at io.quarkus.hibernate.orm.runtime.JPAConfig$LazyPersistenceUnit.get(JPAConfig.java:165)
        at io.quarkus.hibernate.orm.runtime.JPAConfig$1.run(JPAConfig.java:61)
        at java.base/java.lang.Thread.run(Thread.java:1583)
Caused by: org.h2.jdbc.JdbcSQLIntegrityConstraintViolationException: Unique index or primary key violation: "PRIMARY KEY ON PUBLIC.SHAREDENTITY(ID) ( /* key:2 */ CAST(2 AS BIGINT), 'import.sql load script entity')"; SQL statement:
INSERT INTO SharedEntity(id, name) VALUES(2, 'import.sql load script entity') [23505-230]
        at org.h2.message.DbException.getJdbcSQLException(DbException.java:520)
        at org.h2.message.DbException.getJdbcSQLException(DbException.java:489)
        at org.h2.message.DbException.get(DbException.java:223)
        at org.h2.message.DbException.get(DbException.java:199)
        at org.h2.mvstore.db.MVPrimaryIndex.add(MVPrimaryIndex.java:121)
        at org.h2.mvstore.db.MVTable.addRow(MVTable.java:518)
        at org.h2.command.dml.Insert.insertRows(Insert.java:174)
        at org.h2.command.dml.Insert.update(Insert.java:135)
        at org.h2.command.dml.DataChangeStatement.update(DataChangeStatement.java:74)
        at org.h2.command.CommandContainer.update(CommandContainer.java:139)
        at org.h2.command.Command.executeUpdate(Command.java:304)
        at org.h2.command.Command.executeUpdate(Command.java:248)
        at org.h2.server.TcpServerThread.process(TcpServerThread.java:386)
        at org.h2.server.TcpServerThread.run(TcpServerThread.java:193)
        at java.base/java.lang.Thread.run(Thread.java:1583)

        at org.h2.message.DbException.getJdbcSQLException(DbException.java:520)
        at org.h2.engine.SessionRemote.readSQLException(SessionRemote.java:663)
        at org.h2.engine.SessionRemote.readException(SessionRemote.java:646)
        at org.h2.engine.SessionRemote.done(SessionRemote.java:620)
        at org.h2.command.CommandRemote.executeUpdate(CommandRemote.java:225)
        at org.h2.jdbc.JdbcStatement.executeInternal(JdbcStatement.java:262)
        at org.h2.jdbc.JdbcStatement.execute(JdbcStatement.java:231)
        at io.agroal.pool.wrapper.StatementWrapper.execute(StatementWrapper.java:220)
        at org.hibernate.tool.schema.internal.exec.GenerationTargetToDatabase.accept(GenerationTargetToDatabase.java:79)
        ... 21 more

which is essentially the same cause as the issue in the TestSuite