@Id Generate not incremented correctly when using our own sequence generator name

[yaakov-berkovitch]

Hi,

I have a strange behavior (not correct) when using my own @Id generator sequence, let's say objects_id_seq. See below:

    @Id
    @GeneratedValue(strategy = GenerationType.SEQUENCE, generator = "objects_id_seq")
    @SequenceGenerator(name = "objects_id_seq", sequenceName = "objects_id_seq")
    private Long id;

When I persist a list of entities entity with the above declaration, I failed to insert objects because of duplicated key value: Caught exception - exception = javax.persistence.PersistenceException: org.hibernate.HibernateException: io.vertx.pgclient.PgException: { "message": "duplicate key value violates unique constraint \"project_pkey\"", "severity": "ERROR", "code": "23505", "detail": "Key (project_id)=(2) already exists.", "file": "nbtinsert.c", "line": "570", "routine": "_bt_check_unique", "schema": "public", "table": "project", "constraint": "project_pkey" } The first time it works properly, but the second time we run the test, the first object is created and then from the second it failed. We can see also in the output that the sequence increment was called only once Hibernate: select nextval ('hibernate_sequence')

But if I use the default sequence name set by Hibernate (hibernate_seq), all is working properly.

    @Id
    @GeneratedValue(strategy = GenerationType.SEQUENCE)
    private Long id;

I can provide use a simple project that reproduces the issue.

Thanks.

[gavinking]

Which version are you using? Because there were some recent bugfixes in that area.

[yaakov-berkovitch]

1.0.0.CR4

[gavinking]

We can see also in the output that the sequence increment was called only once

That's not surprising, by the way, since by default it uses blocking (hi/lo).

1.0.0.CR4

OK, so you should already have the fix.

Well then could you please send us a (simple) isolated test case that reproduced the failure? Thanks.

[yaakov-berkovitch]

[sequence-issue.zip](https://github.com/hibernate/hibernate-reactive/files/6468038/sequence-issue.zip) You can use the test, just edit the persistence.xml :-)

[gavinking]

Well for me the test just hangs on the first SQL call. It never gets as far as executing an insert.

[yaakov-berkovitch]

Really !? Did you create the sequence manually, because I forgot to include the "create sequence objects_id_seq" in the init-schema.sql script. I think that's the reason it hangs. Else I don't see any reason for hanging.

BTW, you can perhaps also use the test you already have https://github.com/hibernate/hibernate-reactive/blob/main/hibernate-reactive-core/src/test/java/org/hibernate/reactive/UUIDGeneratorTest.java. Just add another test to persist a list of entities, and set your own sequence generator in the @Id attribute.

[gavinking]

I added create sequence objects_id_seq; to init_schema.sql, but the test still just hangs.

[DavideD]

Yes, I've tried it as well and I don't think it's related.

[yaakov-berkovitch]

@DavideD you tried to persist a list of entities, right ? And how it can stuck ?! There is no logic, only persist entities. Strange. I run it several time, and only when I use the default hibernate sequence it works.

I provide you the output of the runs with and without my own sequence. Sorry for the long output.

This is the output when using my own sequence:

> Task :compileJava
> Task :processResources
> Task :classes
> Task :compileTestJava
C:\dev\sequence-issue\src\test\java\ManyToManyIntegrationTest.java:55: warning: non-varargs call of varargs method with inexact argument type for last parameter;
                (session, tx) -> session.persist(employees.stream().toArray(Employee[]::new))
                                                                           ^
  cast to Object for a varargs call
  cast to Object[] for a non-varargs call and to suppress this warning
1 warning
> Task :processTestResources
> Task :testClasses
> Task :test
18:42:29.560 [Test worker] DEBUG org.jboss.logging - Logging Provider: org.jboss.logging.Log4j2LoggerProvider
18:42:29.748 [Test worker] DEBUG org.hibernate.reactive.provider.ReactivePersistenceProvider - Located and parsed 1 persistence units; checking each
18:42:29.749 [Test worker] DEBUG org.hibernate.reactive.provider.ReactivePersistenceProvider - Checking persistence-unit [name=appviz-persistence-unit, explicit-provider=org.hibernate.reactive.provider.ReactivePersistenceProvider] against incoming persistence unit name [appviz-persistence-unit]
18:42:29.751 [Test worker] DEBUG org.hibernate.jpa.boot.spi.ProviderChecker - Persistence-unit [appviz-persistence-unit] requested PersistenceProvider [org.hibernate.reactive.provider.ReactivePersistenceProvider]
18:42:29.763 [Test worker] DEBUG org.hibernate.jpa.internal.util.LogHelper - PersistenceUnitInfo [
    name: appviz-persistence-unit
    persistence provider classname: org.hibernate.reactive.provider.ReactivePersistenceProvider
    classloader: null
    excludeUnlistedClasses: false
    JTA datasource: null
    Non JTA datasource: null
    Transaction type: RESOURCE_LOCAL
    PU root URL: file:/C:/dev/sequence-issue/build/resources/test/
    Shared Cache Mode: null
    Validation Mode: null
    Jar files URLs []
    Managed classes names [
        model.Employee
        model.Project]
    Mapping files names []
    Properties [
        javax.persistence.jdbc.driver: org.postgresql.Driver
        hibernate.jdbc.time_zone: UTC
        javax.persistence.jdbc.password: demo
        javax.persistence.schema-generation.database.motion: drop-and-create
        javax.persistence.sql-load-script-source: knowledge.sql
        javax.persistence.jdbc.url: jdbc:postgresql://10.20.14.3:5432/demo
        javax.persistence.jdbc.user: demo]
18:42:29.774 [Test worker] DEBUG org.hibernate.integrator.internal.IntegratorServiceImpl - Adding Integrator [org.hibernate.cfg.beanvalidation.BeanValidationIntegrator].
18:42:29.778 [Test worker] DEBUG org.hibernate.integrator.internal.IntegratorServiceImpl - Adding Integrator [org.hibernate.secure.spi.JaccIntegrator].
18:42:29.788 [Test worker] DEBUG org.hibernate.integrator.internal.IntegratorServiceImpl - Adding Integrator [org.hibernate.cache.internal.CollectionCacheInvalidator].
18:42:29.791 [Test worker] DEBUG org.hibernate.integrator.internal.IntegratorServiceImpl - Adding Integrator [org.hibernate.reactive.provider.impl.ReactiveIntegrator].
18:42:29.860 [Test worker] INFO org.hibernate.Version - HHH000412: Hibernate ORM core version 5.4.31.Final
18:42:29.863 [Test worker] DEBUG org.hibernate.cfg.Environment - HHH000206: hibernate.properties not found
18:42:30.066 [Test worker] DEBUG org.hibernate.service.spi.ServiceBinding - Overriding existing service binding [org.hibernate.secure.spi.JaccService]
18:42:30.088 [Test worker] DEBUG org.hibernate.cache.internal.RegionFactoryInitiator - Cannot default RegionFactory based on registered strategies as `[]` RegionFactory strategies were registered
18:42:30.101 [Test worker] DEBUG org.hibernate.cache.internal.RegionFactoryInitiator - Cache region factory : org.hibernate.cache.internal.NoCachingRegionFactory
18:42:30.136 [Test worker] INFO org.hibernate.annotations.common.Version - HCANN000001: Hibernate Commons Annotations {5.1.2.Final}
18:42:30.200 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration boolean -> org.hibernate.type.BooleanType@78bcaffb
18:42:30.201 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration boolean -> org.hibernate.type.BooleanType@78bcaffb
18:42:30.201 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Boolean -> org.hibernate.type.BooleanType@78bcaffb
18:42:30.203 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration numeric_boolean -> org.hibernate.type.NumericBooleanType@7c62db59
18:42:30.206 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration true_false -> org.hibernate.type.TrueFalseType@7c9c2c82
18:42:30.207 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration yes_no -> org.hibernate.type.YesNoType@4351230f
18:42:30.209 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration byte -> org.hibernate.type.ByteType@f7bdc82
18:42:30.209 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration byte -> org.hibernate.type.ByteType@f7bdc82
18:42:30.209 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Byte -> org.hibernate.type.ByteType@f7bdc82
18:42:30.211 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration character -> org.hibernate.type.CharacterType@654a7eaf
18:42:30.212 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration char -> org.hibernate.type.CharacterType@654a7eaf
18:42:30.212 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Character -> org.hibernate.type.CharacterType@654a7eaf
18:42:30.214 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration short -> org.hibernate.type.ShortType@501cf772
18:42:30.214 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration short -> org.hibernate.type.ShortType@501cf772
18:42:30.214 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Short -> org.hibernate.type.ShortType@501cf772
18:42:30.216 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration integer -> org.hibernate.type.IntegerType@25a6f7b4
18:42:30.216 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration int -> org.hibernate.type.IntegerType@25a6f7b4
18:42:30.216 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Integer -> org.hibernate.type.IntegerType@25a6f7b4
18:42:30.219 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration long -> org.hibernate.type.LongType@12934dea
18:42:30.219 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration long -> org.hibernate.type.LongType@12934dea
18:42:30.219 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Long -> org.hibernate.type.LongType@12934dea
18:42:30.221 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration float -> org.hibernate.type.FloatType@76471d3
18:42:30.222 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration float -> org.hibernate.type.FloatType@76471d3
18:42:30.222 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Float -> org.hibernate.type.FloatType@76471d3
18:42:30.224 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration double -> org.hibernate.type.DoubleType@1fcbe235
18:42:30.224 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration double -> org.hibernate.type.DoubleType@1fcbe235
18:42:30.224 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Double -> org.hibernate.type.DoubleType@1fcbe235
18:42:30.227 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration big_decimal -> org.hibernate.type.BigDecimalType@41d6fd15
18:42:30.227 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.math.BigDecimal -> org.hibernate.type.BigDecimalType@41d6fd15
18:42:30.229 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration big_integer -> org.hibernate.type.BigIntegerType@58e5acbc
18:42:30.229 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.math.BigInteger -> org.hibernate.type.BigIntegerType@58e5acbc
18:42:30.230 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration string -> org.hibernate.type.StringType@320ab744
18:42:30.231 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.String -> org.hibernate.type.StringType@320ab744
18:42:30.231 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration nstring -> org.hibernate.type.StringNVarcharType@79f838e6
18:42:30.232 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration ncharacter -> org.hibernate.type.CharacterNCharType@6627a075
18:42:30.234 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration url -> org.hibernate.type.UrlType@28347872
18:42:30.234 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.net.URL -> org.hibernate.type.UrlType@28347872
18:42:30.236 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration Duration -> org.hibernate.type.DurationType@97e22f4
18:42:30.236 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.time.Duration -> org.hibernate.type.DurationType@97e22f4
18:42:30.255 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration Instant -> org.hibernate.type.InstantType@5f51297f
18:42:30.255 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.time.Instant -> org.hibernate.type.InstantType@5f51297f
18:42:30.258 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration LocalDateTime -> org.hibernate.type.LocalDateTimeType@45d77352
18:42:30.258 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.time.LocalDateTime -> org.hibernate.type.LocalDateTimeType@45d77352
18:42:30.260 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration LocalDate -> org.hibernate.type.LocalDateType@9a43b5e
18:42:30.260 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.time.LocalDate -> org.hibernate.type.LocalDateType@9a43b5e
18:42:30.262 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration LocalTime -> org.hibernate.type.LocalTimeType@1472956f
18:42:30.262 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.time.LocalTime -> org.hibernate.type.LocalTimeType@1472956f
18:42:30.265 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration OffsetDateTime -> org.hibernate.type.OffsetDateTimeType@35d6e439
18:42:30.265 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.time.OffsetDateTime -> org.hibernate.type.OffsetDateTimeType@35d6e439
18:42:30.268 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration OffsetTime -> org.hibernate.type.OffsetTimeType@5ba2458b
18:42:30.268 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.time.OffsetTime -> org.hibernate.type.OffsetTimeType@5ba2458b
18:42:30.295 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration ZonedDateTime -> org.hibernate.type.ZonedDateTimeType@26443d23
18:42:30.295 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.time.ZonedDateTime -> org.hibernate.type.ZonedDateTimeType@26443d23
18:42:30.298 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration date -> org.hibernate.type.DateType@5980ebc4
18:42:30.298 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.sql.Date -> org.hibernate.type.DateType@5980ebc4
18:42:30.300 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration time -> org.hibernate.type.TimeType@219d3b98
18:42:30.301 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.sql.Time -> org.hibernate.type.TimeType@219d3b98
18:42:30.303 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration timestamp -> org.hibernate.type.TimestampType@b886f8e
18:42:30.303 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.sql.Timestamp -> org.hibernate.type.TimestampType@b886f8e
18:42:30.304 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.util.Date -> org.hibernate.type.TimestampType@b886f8e
18:42:30.306 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration dbtimestamp -> org.hibernate.type.DbTimestampType@1c58640a
18:42:30.312 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration calendar -> org.hibernate.type.CalendarType@296f44e2
18:42:30.312 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.util.Calendar -> org.hibernate.type.CalendarType@296f44e2
18:42:30.312 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.util.GregorianCalendar -> org.hibernate.type.CalendarType@296f44e2
18:42:30.313 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration calendar_date -> org.hibernate.type.CalendarDateType@9f5ced2
18:42:30.315 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration calendar_time -> org.hibernate.type.CalendarTimeType@dd58094
18:42:30.316 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration locale -> org.hibernate.type.LocaleType@4602565
18:42:30.316 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.util.Locale -> org.hibernate.type.LocaleType@4602565
18:42:30.317 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration currency -> org.hibernate.type.CurrencyType@6f607d85
18:42:30.317 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.util.Currency -> org.hibernate.type.CurrencyType@6f607d85
18:42:30.322 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration timezone -> org.hibernate.type.TimeZoneType@18dd97a
18:42:30.322 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.util.TimeZone -> org.hibernate.type.TimeZoneType@18dd97a
18:42:30.323 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration class -> org.hibernate.type.ClassType@25c4e191
18:42:30.323 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Class -> org.hibernate.type.ClassType@25c4e191
18:42:30.325 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration uuid-binary -> org.hibernate.type.UUIDBinaryType@7d22dcd0
18:42:30.325 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.util.UUID -> org.hibernate.type.UUIDBinaryType@7d22dcd0
18:42:30.325 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration uuid-char -> org.hibernate.type.UUIDCharType@c2a2786
18:42:30.327 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration binary -> org.hibernate.type.BinaryType@52cc3fc5
18:42:30.327 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration byte[] -> org.hibernate.type.BinaryType@52cc3fc5
18:42:30.328 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration [B -> org.hibernate.type.BinaryType@52cc3fc5
18:42:30.329 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration wrapper-binary -> org.hibernate.type.WrapperBinaryType@3075ca37
18:42:30.329 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration Byte[] -> org.hibernate.type.WrapperBinaryType@3075ca37
18:42:30.329 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration [Ljava.lang.Byte; -> org.hibernate.type.WrapperBinaryType@3075ca37
18:42:30.331 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration row_version -> org.hibernate.type.RowVersionType@433f8316
18:42:30.331 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration image -> org.hibernate.type.ImageType@2caed782
18:42:30.333 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration characters -> org.hibernate.type.CharArrayType@72169d60
18:42:30.333 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration char[] -> org.hibernate.type.CharArrayType@72169d60
18:42:30.333 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration [C -> org.hibernate.type.CharArrayType@72169d60
18:42:30.335 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration wrapper-characters -> org.hibernate.type.CharacterArrayType@7a9114bd
18:42:30.335 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration [Ljava.lang.Character; -> org.hibernate.type.CharacterArrayType@7a9114bd
18:42:30.335 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration Character[] -> org.hibernate.type.CharacterArrayType@7a9114bd
18:42:30.336 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration text -> org.hibernate.type.TextType@1062cc43
18:42:30.337 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration ntext -> org.hibernate.type.NTextType@85e7e09
18:42:30.340 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration blob -> org.hibernate.type.BlobType@c777019
18:42:30.340 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.sql.Blob -> org.hibernate.type.BlobType@c777019
18:42:30.343 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration materialized_blob -> org.hibernate.type.MaterializedBlobType@24f03888
18:42:30.346 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration clob -> org.hibernate.type.ClobType@75da4a45
18:42:30.346 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.sql.Clob -> org.hibernate.type.ClobType@75da4a45
18:42:30.349 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration nclob -> org.hibernate.type.NClobType@4317cb98
18:42:30.349 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.sql.NClob -> org.hibernate.type.NClobType@4317cb98
18:42:30.349 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration materialized_clob -> org.hibernate.type.MaterializedClobType@7a3a8fb4
18:42:30.350 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration materialized_nclob -> org.hibernate.type.MaterializedNClobType@4c036de
18:42:30.353 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration serializable -> org.hibernate.type.SerializableType@73c51eb3
18:42:30.358 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration object -> org.hibernate.type.ObjectType@6a82b4e2
18:42:30.358 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Object -> org.hibernate.type.ObjectType@6a82b4e2
18:42:30.359 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration imm_date -> org.hibernate.type.AdaptedImmutableType@5152609b
18:42:30.359 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration imm_time -> org.hibernate.type.AdaptedImmutableType@2e08ebf7
18:42:30.359 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration imm_timestamp -> org.hibernate.type.AdaptedImmutableType@664ac875
18:42:30.359 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration imm_dbtimestamp -> org.hibernate.type.AdaptedImmutableType@2b94d14b
18:42:30.359 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration imm_calendar -> org.hibernate.type.AdaptedImmutableType@69002a1a
18:42:30.360 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration imm_calendar_date -> org.hibernate.type.AdaptedImmutableType@3e239e
18:42:30.360 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration imm_binary -> org.hibernate.type.AdaptedImmutableType@30874a8c
18:42:30.360 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration imm_serializable -> org.hibernate.type.AdaptedImmutableType@3d94cb5e
18:42:30.375 [Test worker] DEBUG org.hibernate.boot.internal.BootstrapContextImpl - Injecting ScanEnvironment [org.hibernate.jpa.boot.internal.StandardJpaScanEnvironmentImpl@7741836b] into BootstrapContext; was [null]
18:42:30.375 [Test worker] DEBUG org.hibernate.boot.internal.BootstrapContextImpl - Injecting ScanOptions [org.hibernate.boot.archive.scan.internal.StandardScanOptions@10375c5b] into BootstrapContext; was [org.hibernate.boot.archive.scan.internal.StandardScanOptions@51bbb35e]
18:42:30.412 [Test worker] DEBUG org.hibernate.boot.internal.BootstrapContextImpl - Injecting JPA temp ClassLoader [null] into BootstrapContext; was [null]
18:42:30.412 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - ClassLoaderAccessImpl#injectTempClassLoader(null) [was null]
18:42:30.428 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [uuid2] -> [org.hibernate.id.UUIDGenerator]
18:42:30.428 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [guid] -> [org.hibernate.id.GUIDGenerator]
18:42:30.429 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [uuid] -> [org.hibernate.id.UUIDHexGenerator]
18:42:30.429 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [uuid.hex] -> [org.hibernate.id.UUIDHexGenerator]
18:42:30.430 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [assigned] -> [org.hibernate.id.Assigned]
18:42:30.432 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [identity] -> [org.hibernate.id.IdentityGenerator]
18:42:30.432 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [select] -> [org.hibernate.id.SelectGenerator]
18:42:30.434 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [sequence] -> [org.hibernate.id.enhanced.SequenceStyleGenerator]
18:42:30.435 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [seqhilo] -> [org.hibernate.id.SequenceHiLoGenerator]
18:42:30.436 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [increment] -> [org.hibernate.id.IncrementGenerator]
18:42:30.437 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [foreign] -> [org.hibernate.id.ForeignGenerator]
18:42:30.438 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [sequence-identity] -> [org.hibernate.id.SequenceIdentityGenerator]
18:42:30.438 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [enhanced-sequence] -> [org.hibernate.id.enhanced.SequenceStyleGenerator]
18:42:30.439 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [enhanced-table] -> [org.hibernate.id.enhanced.TableGenerator]
18:42:30.477 [Test worker] INFO org.hibernate.dialect.Dialect - HHH000400: Using dialect: org.hibernate.dialect.PostgreSQL10Dialect
18:42:30.496 [Test worker] DEBUG org.hibernate.engine.jdbc.env.spi.IdentifierHelperBuilder - IdentifierCaseStrategy for both quoted and unquoted identifiers was set to the same strategy [MIXED]; that will likely lead to problems in schema update and validation if using quoted identifiers
18:42:30.502 [Test worker] DEBUG org.hibernate.engine.jdbc.env.internal.LobCreatorBuilderImpl - HHH000422: Disabling contextual LOB creation as connection was null
18:42:30.508 [Test worker] DEBUG org.hibernate.boot.model.relational.Namespace - Created database namespace [logicalName=Name{catalog=null, schema=null}, physicalName=Name{catalog=null, schema=null}]
18:42:30.510 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration pg-uuid -> org.hibernate.type.PostgresUUIDType@198598ea
18:42:30.511 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.util.UUID -> org.hibernate.type.PostgresUUIDType@198598ea
18:42:30.511 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - HHH000270: Type registration [java.util.UUID] overrides previous : org.hibernate.type.UUIDBinaryType@7d22dcd0
18:42:30.516 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration materialized_blob -> org.hibernate.reactive.provider.impl.ReactiveTypeContributor$BlobType@4a84094
18:42:30.516 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - HHH000270: Type registration [materialized_blob] overrides previous : org.hibernate.type.MaterializedBlobType@24f03888
18:42:30.517 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration materialized_clob -> org.hibernate.reactive.provider.impl.ReactiveTypeContributor$ClobType@750aecf8
18:42:30.517 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - HHH000270: Type registration [materialized_clob] overrides previous : org.hibernate.type.MaterializedClobType@7a3a8fb4
18:42:30.518 [Test worker] DEBUG org.hibernate.type.spi.TypeConfiguration$Scope - Scoping TypeConfiguration [org.hibernate.type.spi.TypeConfiguration@201603dd] to MetadataBuildingContext [org.hibernate.boot.internal.MetadataBuildingContextRootImpl@34cd562]
18:42:30.583 [Test worker] WARN org.hibernate.cfg.AnnotationBinder - HHH000503: A class should not be annotated with both @Inheritance and @MappedSuperclass. @Inheritance will be ignored for: model.BaseEntity.
18:42:30.593 [Test worker] DEBUG org.hibernate.cfg.AnnotationBinder - Binding entity from annotated class: model.Employee
18:42:30.614 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3DiscriminatorColumn{logicalColumnName'DTYPE', discriminatorTypeName='string'}
18:42:30.622 [Test worker] DEBUG org.hibernate.cfg.annotations.EntityBinder - Import with entity name Employee
18:42:30.625 [Test worker] DEBUG org.hibernate.cfg.annotations.EntityBinder - Bind entity model.Employee on table Employee
18:42:30.660 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Column(s) overridden for property id
18:42:30.662 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Employee), mappingColumn=employee_id, insertable=true, updatable=true, unique=false}
18:42:30.665 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - Not known whether passed class name [model.Employee] is safe
18:42:30.666 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - No temp ClassLoader provided; using live ClassLoader for loading potentially unsafe class : model.Employee
18:42:30.667 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - MetadataSourceProcessor property id with lazy=false
18:42:30.669 [Test worker] DEBUG org.hibernate.cfg.AbstractPropertyHolder - Attempting to locate auto-apply AttributeConverter for property [model.Employee:id]
18:42:30.672 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - building SimpleValue for id
18:42:30.675 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - Building property id
18:42:30.681 [Test worker] DEBUG org.hibernate.cfg.BinderHelper - #makeIdGenerator(org.hibernate.mapping.SimpleValue([org.hibernate.mapping.Column(employee_id)]), id, org.hibernate.id.enhanced.SequenceStyleGenerator, objects_id_seq, ...)
18:42:30.683 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Employee), mappingColumn=first_name, insertable=true, updatable=true, unique=false}
18:42:30.684 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - MetadataSourceProcessor property firstName with lazy=false
18:42:30.684 [Test worker] DEBUG org.hibernate.cfg.AbstractPropertyHolder - Attempting to locate auto-apply AttributeConverter for property [model.Employee:firstName]
18:42:30.684 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - building SimpleValue for firstName
18:42:30.684 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - Building property firstName
18:42:30.685 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Employee), mappingColumn=last_name, insertable=true, updatable=true, unique=false}
18:42:30.685 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - MetadataSourceProcessor property lastName with lazy=false
18:42:30.685 [Test worker] DEBUG org.hibernate.cfg.AbstractPropertyHolder - Attempting to locate auto-apply AttributeConverter for property [model.Employee:lastName]
18:42:30.685 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - building SimpleValue for lastName
18:42:30.685 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - Building property lastName
18:42:30.688 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Employee), mappingColumn=null, insertable=true, updatable=true, unique=false}
18:42:30.700 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Employee), mappingColumn=element, insertable=true, updatable=true, unique=false}
18:42:30.701 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Employee), mappingColumn=projects_KEY, insertable=true, updatable=true, unique=false}
18:42:30.703 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3JoinColumn{logicalColumnName='projects_KEY', referencedColumn='null', mappedBy='null'}
18:42:30.704 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3JoinColumn{logicalColumnName='employee_id', referencedColumn='', mappedBy=''}
18:42:30.705 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3JoinColumn{logicalColumnName='project_id', referencedColumn='', mappedBy=''}
18:42:30.705 [Test worker] DEBUG org.hibernate.cfg.annotations.CollectionBinder - Collection role: model.Employee.projects
18:42:30.709 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - Building property projects
18:42:30.711 [Test worker] DEBUG org.hibernate.cfg.AnnotationBinder - Binding entity from annotated class: model.Project
18:42:30.712 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3DiscriminatorColumn{logicalColumnName'DTYPE', discriminatorTypeName='string'}
18:42:30.712 [Test worker] DEBUG org.hibernate.cfg.annotations.EntityBinder - Import with entity name Project
18:42:30.712 [Test worker] DEBUG org.hibernate.cfg.annotations.EntityBinder - Bind entity model.Project on table Project
18:42:30.713 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Column(s) overridden for property id
18:42:30.713 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Project), mappingColumn=project_id, insertable=true, updatable=true, unique=false}
18:42:30.714 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - Not known whether passed class name [model.Project] is safe
18:42:30.714 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - No temp ClassLoader provided; using live ClassLoader for loading potentially unsafe class : model.Project
18:42:30.714 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - MetadataSourceProcessor property id with lazy=false
18:42:30.714 [Test worker] DEBUG org.hibernate.cfg.AbstractPropertyHolder - Attempting to locate auto-apply AttributeConverter for property [model.Project:id]
18:42:30.714 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - building SimpleValue for id
18:42:30.714 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - Building property id
18:42:30.714 [Test worker] DEBUG org.hibernate.cfg.BinderHelper - #makeIdGenerator(org.hibernate.mapping.SimpleValue([org.hibernate.mapping.Column(project_id)]), id, org.hibernate.id.enhanced.SequenceStyleGenerator, objects_id_seq, ...)
18:42:30.714 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Project), mappingColumn=null, insertable=true, updatable=true, unique=false}
18:42:30.715 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Project), mappingColumn=element, insertable=true, updatable=true, unique=false}
18:42:30.715 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Project), mappingColumn=employees_KEY, insertable=true, updatable=true, unique=false}
18:42:30.715 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3JoinColumn{logicalColumnName='employees_KEY', referencedColumn='null', mappedBy='null'}
18:42:30.715 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3JoinColumn{logicalColumnName='null', referencedColumn='null', mappedBy='projects'}
18:42:30.715 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3JoinColumn{logicalColumnName='null', referencedColumn='null', mappedBy='projects'}
18:42:30.715 [Test worker] DEBUG org.hibernate.cfg.annotations.CollectionBinder - Collection role: model.Project.employees
18:42:30.716 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - Building property employees
18:42:30.716 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Project), mappingColumn=title, insertable=true, updatable=true, unique=false}
18:42:30.716 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - MetadataSourceProcessor property title with lazy=false
18:42:30.716 [Test worker] DEBUG org.hibernate.cfg.AbstractPropertyHolder - Attempting to locate auto-apply AttributeConverter for property [model.Project:title]
18:42:30.716 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - building SimpleValue for title
18:42:30.717 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - Building property title
18:42:30.719 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - Starting fillSimpleValue for id
18:42:30.720 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - Starting fillSimpleValue for firstName
18:42:30.720 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - Starting fillSimpleValue for lastName
18:42:30.720 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - Starting fillSimpleValue for id
18:42:30.720 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - Starting fillSimpleValue for title
18:42:30.722 [Test worker] DEBUG org.hibernate.mapping.PrimaryKey - Forcing column [employee_id] to be non-null as it is part of the primary key for table [employee]
18:42:30.722 [Test worker] DEBUG org.hibernate.mapping.PrimaryKey - Forcing column [project_id] to be non-null as it is part of the primary key for table [project]
18:42:30.723 [Test worker] DEBUG org.hibernate.cfg.CollectionSecondPass - Second pass for collection: model.Employee.projects
18:42:30.723 [Test worker] DEBUG org.hibernate.cfg.annotations.CollectionBinder - Binding as ManyToMany: model.Employee.projects
18:42:30.732 [Test worker] DEBUG org.hibernate.mapping.PrimaryKey - Forcing column [employee_id] to be non-null as it is part of the primary key for table [employee_project]
18:42:30.732 [Test worker] DEBUG org.hibernate.mapping.PrimaryKey - Forcing column [project_id] to be non-null as it is part of the primary key for table [employee_project]
18:42:30.732 [Test worker] DEBUG org.hibernate.cfg.CollectionSecondPass - Mapped collection key: employee_id, element: project_id
18:42:30.732 [Test worker] DEBUG org.hibernate.cfg.CollectionSecondPass - Second pass for collection: model.Project.employees
18:42:30.734 [Test worker] DEBUG org.hibernate.cfg.annotations.CollectionBinder - Binding as ManyToMany: model.Project.employees
18:42:30.734 [Test worker] DEBUG org.hibernate.cfg.annotations.TableBinder - Retrieving property model.Employee.projects
18:42:30.735 [Test worker] DEBUG org.hibernate.cfg.CollectionSecondPass - Mapped collection key: project_id, element: employee_id
18:42:30.735 [Test worker] DEBUG org.hibernate.boot.internal.InFlightMetadataCollectorImpl - Resolving reference to class: model.Project
18:42:30.738 [Test worker] DEBUG org.hibernate.boot.internal.InFlightMetadataCollectorImpl - Resolving reference to class: model.Employee
18:42:30.742 [Test worker] DEBUG org.hibernate.boot.internal.InFlightMetadataCollectorImpl - Resolving reference to class: model.Project
18:42:30.742 [Test worker] DEBUG org.hibernate.boot.internal.InFlightMetadataCollectorImpl - Resolving reference to class: model.Employee
18:42:30.827 [Test worker] DEBUG org.hibernate.internal.SessionFactoryImpl - Building session factory
18:42:30.828 [Test worker] DEBUG org.hibernate.cfg.Settings - SessionFactory name : null
18:42:30.828 [Test worker] DEBUG org.hibernate.cfg.Settings - Automatic flush during beforeCompletion(): enabled
18:42:30.828 [Test worker] DEBUG org.hibernate.cfg.Settings - Automatic session close at end of transaction: disabled
18:42:30.828 [Test worker] DEBUG org.hibernate.cfg.Settings - Statistics: disabled
18:42:30.828 [Test worker] DEBUG org.hibernate.cfg.Settings - Deleted entity synthetic identifier rollback: disabled
18:42:30.828 [Test worker] DEBUG org.hibernate.cfg.Settings - Default entity-mode: pojo
18:42:30.829 [Test worker] DEBUG org.hibernate.cfg.Settings - Check Nullability in Core (should be disabled when Bean Validation is on): enabled
18:42:30.829 [Test worker] DEBUG org.hibernate.cfg.Settings - Allow initialization of lazy state outside session : disabled
18:42:30.829 [Test worker] DEBUG org.hibernate.cfg.Settings - Using BatchFetchStyle : LEGACY
18:42:30.829 [Test worker] DEBUG org.hibernate.cfg.Settings - Default batch fetch size: -1
18:42:30.829 [Test worker] DEBUG org.hibernate.cfg.Settings - Maximum outer join fetch depth: null
18:42:30.829 [Test worker] DEBUG org.hibernate.cfg.Settings - Default null ordering: NONE
18:42:30.829 [Test worker] DEBUG org.hibernate.cfg.Settings - Order SQL updates by primary key: disabled
18:42:30.829 [Test worker] DEBUG org.hibernate.cfg.Settings - Order SQL inserts for batching: disabled
18:42:30.829 [Test worker] DEBUG org.hibernate.cfg.Settings - multi-tenancy strategy : NONE
18:42:30.829 [Test worker] DEBUG org.hibernate.cfg.Settings - JTA Track by Thread: enabled
18:42:30.829 [Test worker] DEBUG org.hibernate.cfg.Settings - Query language substitutions: {}
18:42:30.829 [Test worker] DEBUG org.hibernate.cfg.Settings - Named query checking : enabled
18:42:30.830 [Test worker] DEBUG org.hibernate.cfg.Settings - Second-level cache: disabled
18:42:30.830 [Test worker] DEBUG org.hibernate.cfg.Settings - Second-level query cache: disabled
18:42:30.830 [Test worker] DEBUG org.hibernate.cfg.Settings - Second-level query cache factory: null
18:42:30.830 [Test worker] DEBUG org.hibernate.cfg.Settings - Second-level cache region prefix: null
18:42:30.830 [Test worker] DEBUG org.hibernate.cfg.Settings - Optimize second-level cache for minimal puts: disabled
18:42:30.830 [Test worker] DEBUG org.hibernate.cfg.Settings - Structured second-level cache entries: disabled
18:42:30.830 [Test worker] DEBUG org.hibernate.cfg.Settings - Second-level cache direct-reference entries: disabled
18:42:30.830 [Test worker] DEBUG org.hibernate.cfg.Settings - Automatic eviction of collection cache: disabled
18:42:30.830 [Test worker] DEBUG org.hibernate.cfg.Settings - JDBC batch size: 0
18:42:30.830 [Test worker] DEBUG org.hibernate.cfg.Settings - JDBC batch updates for versioned data: enabled
18:42:30.830 [Test worker] DEBUG org.hibernate.cfg.Settings - Scrollable result sets: disabled
18:42:30.831 [Test worker] DEBUG org.hibernate.cfg.Settings - Wrap result sets: disabled
18:42:30.831 [Test worker] DEBUG org.hibernate.cfg.Settings - JDBC3 getGeneratedKeys(): disabled
18:42:30.831 [Test worker] DEBUG org.hibernate.cfg.Settings - JDBC result set fetch size: null
18:42:30.831 [Test worker] DEBUG org.hibernate.cfg.Settings - Connection release mode: AFTER_TRANSACTION
18:42:30.831 [Test worker] DEBUG org.hibernate.cfg.Settings - Generate SQL with comments: disabled
18:42:30.831 [Test worker] DEBUG org.hibernate.cfg.Settings - JPA compliance - query : disabled
18:42:30.831 [Test worker] DEBUG org.hibernate.cfg.Settings - JPA compliance - closed-handling : disabled
18:42:30.831 [Test worker] DEBUG org.hibernate.cfg.Settings - JPA compliance - lists : disabled
18:42:30.831 [Test worker] DEBUG org.hibernate.cfg.Settings - JPA compliance - transactions : disabled
18:42:30.924 [Test worker] DEBUG org.hibernate.internal.SessionFactoryImpl - Session factory constructed with filter configurations : {}
18:42:30.925 [Test worker] DEBUG org.hibernate.internal.SessionFactoryImpl - Instantiating session factory with properties: {sun.desktop=windows, awt.toolkit=sun.awt.windows.WToolkit, java.specification.version=1.8, file.encoding.pkg=sun.io, sun.cpu.isalist=amd64, sun.jnu.encoding=Cp1252, hibernate.dialect=org.hibernate.dialect.PostgreSQL10Dialect, org.gradle.test.worker=1, java.class.path=C:\dev\sequence-issue\build\classes\java\test;C:\dev\sequence-issue\build\resources\test;C:\dev\sequence-issue\build\classes\java\main;C:\dev\sequence-issue\build\resources\main;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework.boot\spring-boot-starter-webflux\2.4.5\45bd0ec9dd895e17d5e21f18ca0ca8ddce071e74\spring-boot-starter-webflux-2.4.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.vertx\vertx-web\4.0.3\5d0cec868aadac3e098b1ab6409dc2cebae83930\vertx-web-4.0.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.vertx\vertx-pg-client\4.0.3\3f4c6c885312b1b9aa73f66b939339c5d969a04b\vertx-pg-client-4.0.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.vertx\vertx-web-common\4.0.3\274167d895a6170335a5fd7e71d4519b9608d275\vertx-web-common-4.0.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.vertx\vertx-auth-common\4.0.3\177afb713c00713eff76a6cd83a8090c601af474\vertx-auth-common-4.0.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.hibernate.reactive\hibernate-reactive-core\1.0.0.CR4\bc9331436f75b8d2b73738fc4ab5c70676aaf96a\hibernate-reactive-core-1.0.0.CR4.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.vertx\vertx-sql-client\4.0.3\2bb64597f96e27a4a431967bea77592286d35462\vertx-sql-client-4.0.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.vertx\vertx-core\4.0.3\40c496ede9a948ec673df8cf5ab227ff853dd061\vertx-core-4.0.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework.boot\spring-boot-starter-json\2.4.5\5c1623aa53adf10cd98884f60db2d9f5958dbc28\spring-boot-starter-json-2.4.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.fasterxml.jackson.datatype\jackson-datatype-jsr310\2.11.4\ce6fc76bba06623720e5a9308386b6ae74753f4d\jackson-datatype-jsr310-2.11.4.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.fasterxml.jackson.core\jackson-annotations\2.12.3\7275513412694a1aafd08c0287f48469fa0e6e17\jackson-annotations-2.12.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.fasterxml.jackson.datatype\jackson-datatype-jdk8\2.11.4\e1540dea3c6c681ea4e335a960f730861ee3bedb\jackson-datatype-jdk8-2.11.4.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.fasterxml.jackson.module\jackson-module-parameter-names\2.11.4\432e050d79f2282a66c320375d628f1b0842cb12\jackson-module-parameter-names-2.11.4.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.fasterxml.jackson.core\jackson-databind\2.12.3\d6153f8fc60c479ab0f9efb35c034526436a4953\jackson-databind-2.12.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.fasterxml.jackson.core\jackson-core\2.12.3\deb23fe2a7f2b773e18ced2b50d4acc1df8fa366\jackson-core-2.12.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.hibernate\hibernate-core\5.4.31.Final\b0cd5c1d0dbaed84573203fd348a7e64f94d33cb\hibernate-core-5.4.31.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.junit.platform\junit-platform-engine\1.7.1\d276a968c57f5d60a421dedd1f8b6ca2fae09e86\junit-platform-engine-1.7.1.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.junit.jupiter\junit-jupiter-api\5.7.1\a7261dff44e64aea7f621842eac5977fd6d2412d\junit-jupiter-api-5.7.1.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.junit.platform\junit-platform-commons\1.7.1\7c49f0074842d07f4335de2389d624a7437d1407\junit-platform-commons-1.7.1.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.junit.jupiter\junit-jupiter-engine\5.7.1\7fcfa59c5533abb41f86b1408960737eeaf1f49f\junit-jupiter-engine-5.7.1.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework.boot\spring-boot-starter\2.4.5\5f5398f459beb958fc2ece1a433420bf03cec628\spring-boot-starter-2.4.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework.boot\spring-boot-starter-reactor-netty\2.4.5\727f61ad77ff370ffbc33761dd52a71eae2e4204\spring-boot-starter-reactor-netty-2.4.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework\spring-webflux\5.3.6\39370e45b105ae08298beb88a1a5d070995fe3dd\spring-webflux-5.3.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework\spring-web\5.3.6\f9290db7324194921c236ad9a940467f55304fa7\spring-web-5.3.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.projectreactor.netty\reactor-netty-http\1.0.6\6a76dd233bdb1e6dc5364ae1b5c8627c2cb0288f\reactor-netty-http-1.0.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.projectreactor.netty\reactor-netty-core\1.0.6\c24e7e989913d50c2ed592892e55ce1284364ac4\reactor-netty-core-1.0.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-handler-proxy\4.1.63.Final\d8cbdc537d75f219c04a057b984b2f0b55c1dbff\netty-handler-proxy-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-codec-http2\4.1.63.Final\294e90696d8d6e20c889511d2484b37158cb9caa\netty-codec-http2-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-codec-http\4.1.63.Final\f8c9b159dcb76452dc98a370a5511ff993670419\netty-codec-http-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-resolver-dns-native-macos\4.1.63.Final\d72f13b680d361b0e217704bda59ccb4b3256297\netty-resolver-dns-native-macos-4.1.63.Final-osx-x86_64.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-resolver-dns\4.1.63.Final\f44dc8ed52ff8528a88c157879c9baffe464fa46\netty-resolver-dns-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-handler\4.1.63.Final\879a43c2325b08e92e8967218b6ddb0ed4b7a0d3\netty-handler-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-codec-socks\4.1.63.Final\97707b764c9287836dcf626dd03c81f3bbfc86c6\netty-codec-socks-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-codec-dns\4.1.63.Final\91c445232b12e13bf6757579a39bab81e1233af5\netty-codec-dns-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-codec\4.1.63.Final\d4d2fccea88c80e56d59ce1053c53df0f9f4f5db\netty-codec-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-transport-native-epoll\4.1.63.Final\98d898362dd9a00eb23e2857a49cb15ff37353dc\netty-transport-native-epoll-4.1.63.Final-linux-x86_64.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-transport-native-unix-common\4.1.63.Final\85bd91382ec54b300ad3ff59efccbb4fccb22a88\netty-transport-native-unix-common-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-transport\4.1.63.Final\9a8bbe1ba082c9434e6f524d3864a53f340f2df\netty-transport-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-buffer\4.1.63.Final\40028ce5ac7c43f1c9a1439f74637cad04013e23\netty-buffer-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-resolver\4.1.63.Final\d07cd47c101dfa655d6d5cc304d523742fd78ca8\netty-resolver-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-common\4.1.63.Final\e1206b46384d4dcbecee2901f18ce65ecf02e8a4\netty-common-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.vertx\vertx-bridge-common\4.0.3\423970a10bdc4fa14ce4ad18ef3f45d703e53420\vertx-bridge-common-4.0.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.hibernate.common\hibernate-commons-annotations\5.1.2.Final\e59ffdbc6ad09eeb33507b39ffcf287679a498c8\hibernate-commons-annotations-5.1.2.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.jboss.logging\jboss-logging\3.4.1.Final\40fd4d696c55793e996d1ff3c475833f836c2498\jboss-logging-3.4.1.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.ibm.async\asyncutil\0.1.0\440941c382166029a299602e6c9ff5abde1b5143\asyncutil-0.1.0.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.smallrye.reactive\mutiny\0.16.0\c1b40622059bbb3f771317fe75af11c4cbdbb081\mutiny-0.16.0.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\javax.persistence\javax.persistence-api\2.2\25665ac8c0b62f50e6488173233239120fc52c96\javax.persistence-api-2.2.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.javassist\javassist\3.27.0-GA\f63e6aa899e15eca8fdaa402a79af4c417252213\javassist-3.27.0-GA.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\net.bytebuddy\byte-buddy\1.10.22\ef45d7e2cd1c600d279704f492ed5ce2ceb6cdb5\byte-buddy-1.10.22.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\antlr\antlr\2.7.7\83cd2cd674a217ade95a4bb83a8a14f351f48bd0\antlr-2.7.7.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.jboss.spec.javax.transaction\jboss-transaction-api_1.2_spec\1.1.1.Final\a8485cab9484dda36e9a8c319e76b5cc18797b58\jboss-transaction-api_1.2_spec-1.1.1.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.jboss\jandex\2.2.3.Final\d3865101f0666b63586683bd811d754517f331ab\jandex-2.2.3.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.fasterxml\classmate\1.5.1\3fe0bed568c62df5e89f4f174c101eab25345b6c\classmate-1.5.1.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\javax.xml.bind\jaxb-api\2.3.1\8531ad5ac454cc2deb9d4d32c40c4d7451939b5d\jaxb-api-2.3.1.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\javax.activation\javax.activation-api\1.2.0\85262acf3ca9816f9537ca47d5adeabaead7cb16\javax.activation-api-1.2.0.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.dom4j\dom4j\2.1.3\a75914155a9f5808963170ec20653668a2ffd2fd\dom4j-2.1.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.glassfish.jaxb\jaxb-runtime\2.3.4\5f3828d3a345ff3d8acb83f07ba04eccf59e15bb\jaxb-runtime-2.3.4.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.apiguardian\apiguardian-api\1.1.0\fc9dff4bb36d627bdc553de77e1f17efd790876c\apiguardian-api-1.1.0.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework.boot\spring-boot-autoconfigure\2.4.5\32887531b5a125d4840a08f9610aec766de73606\spring-boot-autoconfigure-2.4.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework.boot\spring-boot\2.4.5\d9b82470df2e9549e448d7cef39e578d91f9867f\spring-boot-2.4.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework.boot\spring-boot-starter-logging\2.4.5\1537bbc2e8c6c9db806f9fa11ca7af394d7adacc\spring-boot-starter-logging-2.4.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\jakarta.annotation\jakarta.annotation-api\1.3.5\59eb84ee0d616332ff44aba065f3888cf002cd2d\jakarta.annotation-api-1.3.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework\spring-context\5.3.6\d99d3931528309a4ff49b7d18f76f1657aa16c52\spring-context-5.3.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework\spring-aop\5.3.6\510d9afcfce1fa9a2086906ab87b9989973c251c\spring-aop-5.3.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework\spring-beans\5.3.6\99cc944fb3454410b47fc98d4b148a6205bfe8f6\spring-beans-5.3.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework\spring-expression\5.3.6\33ba1d8dfaab0fd60ce4213a206f5d4fc77ce27a\spring-expression-5.3.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework\spring-core\5.3.6\8f91f60f628075701fde72bb5a43a33feeb27e93\spring-core-5.3.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.yaml\snakeyaml\1.27\359d62567480b07a679dc643f82fc926b100eed5\snakeyaml-1.27.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.projectreactor\reactor-core\3.4.5\c8adeb48f74b16c2dfb4f06a880820b9eaed50cf\reactor-core-3.4.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.reactivestreams\reactive-streams\1.0.3\d9fb7a7926ffa635b3dcaa5049fb2bfa25b3e7d0\reactive-streams-1.0.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.smallrye.common\smallrye-common-annotation\1.6.0\12f41ad4ec9bda7bb38beebb37a29910500b2fc4\smallrye-common-annotation-1.6.0.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\jakarta.xml.bind\jakarta.xml.bind-api\2.3.3\48e3b9cfc10752fba3521d6511f4165bea951801\jakarta.xml.bind-api-2.3.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.glassfish.jaxb\txw2\2.3.4\257fa649d3137a1060d222aefb96b7d1dd5f1286\txw2-2.3.4.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.sun.istack\istack-commons-runtime\3.0.12\cbbe1a62b0cc6c85972e99d52aaee350153dc530\istack-commons-runtime-3.0.12.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.sun.activation\jakarta.activation\1.2.2\74548703f9851017ce2f556066659438019e7eb5\jakarta.activation-1.2.2.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.opentest4j\opentest4j\1.2.0\28c11eb91f9b6d8e200631d46e20a7f407f2a046\opentest4j-1.2.0.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\ch.qos.logback\logback-classic\1.2.3\7c4f3c474fb2c041d8028740440937705ebb473a\logback-classic-1.2.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.apache.logging.log4j\log4j-to-slf4j\2.13.3\966f6fd1af4959d6b12bfa880121d4a2b164f857\log4j-to-slf4j-2.13.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.slf4j\jul-to-slf4j\1.7.30\d58bebff8cbf70ff52b59208586095f467656c30\jul-to-slf4j-1.7.30.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework\spring-jcl\5.3.6\53ddfc5cfdf438a1d3346fc2912c3e3049a8cb76\spring-jcl-5.3.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\ch.qos.logback\logback-core\1.2.3\864344400c3d4d92dfeb0a305dc87d953677c03c\logback-core-1.2.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.slf4j\slf4j-api\1.7.30\b5a4b6d16ab13e34a88fae84c35cd5d68cac922c\slf4j-api-1.7.30.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.apache.logging.log4j\log4j-api\2.13.3\ec1508160b93d274b1add34419b897bae84c6ca9\log4j-api-2.13.3.jar, javax.persistence.sql-load-script-source=knowledge.sql, java.vm.vendor=Oracle Corporation, sun.arch.data.model=64, user.variant=, java.vendor.url=http://java.oracle.com/, user.timezone=Asia/Jerusalem, javax.persistence.jdbc.url=jdbc:postgresql://10.20.14.3:5432/demo, javax.persistence.jdbc.user=****, hibernate.jdbc.time_zone=UTC, os.name=Windows 10, java.vm.specification.version=1.8, user.country=US, sun.java.launcher=SUN_STANDARD, local.setting.IS_JTA_TXN_COORD=false, sun.boot.library.path=C:\Program Files\Java\jdk1.8.0_241\jre\bin, sun.java.command=worker.org.gradle.process.internal.worker.GradleWorkerMain 'Gradle Test Executor 1', sun.cpu.endian=little, user.home=C:\Users\yaakov.berkovitch, user.language=en, java.specification.vendor=Oracle Corporation, org.gradle.native=false, java.home=C:\Program Files\Java\jdk1.8.0_241\jre, file.separator=\, line.separator=
, java.vm.specification.vendor=Oracle Corporation, java.specification.name=Java Platform API Specification, hibernate.transaction.coordinator_class=class org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorBuilderImpl, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, javax.persistence.jdbc.driver=org.postgresql.Driver, sun.boot.class.path=C:\Program Files\Java\jdk1.8.0_241\jre\lib\resources.jar;C:\Program Files\Java\jdk1.8.0_241\jre\lib\rt.jar;C:\Program Files\Java\jdk1.8.0_241\jre\lib\sunrsasign.jar;C:\Program Files\Java\jdk1.8.0_241\jre\lib\jsse.jar;C:\Program Files\Java\jdk1.8.0_241\jre\lib\jce.jar;C:\Program Files\Java\jdk1.8.0_241\jre\lib\charsets.jar;C:\Program Files\Java\jdk1.8.0_241\jre\lib\jfr.jar;C:\Program Files\Java\jdk1.8.0_241\jre\classes, user.script=, sun.management.compiler=HotSpot 64-Bit Tiered Compilers, java.runtime.version=1.8.0_241-b07, user.name=yaakov.berkovitch, path.separator=;, javax.persistence.schema-generation.database.motion=drop-and-create, hibernate.connection.username=****, os.version=10.0, java.endorsed.dirs=C:\Program Files\Java\jdk1.8.0_241\jre\lib\endorsed, java.runtime.name=Java(TM) SE Runtime Environment, hibernate.connection.url=jdbc:postgresql://10.20.14.3:5432/demo, file.encoding=windows-1252, hibernate.ejb.persistenceUnitName=appviz-persistence-unit, java.vm.name=Java HotSpot(TM) 64-Bit Server VM, hibernate.connection.driver_class=org.postgresql.Driver, java.vendor.url.bug=http://bugreport.sun.com/bugreport/, java.io.tmpdir=C:\Users\YAAKOV~1.BER\AppData\Local\Temp\, java.version=1.8.0_241, user.dir=C:\dev\sequence-issue, os.arch=amd64, java.vm.specification.name=Java Virtual Machine Specification, java.awt.printerjob=sun.awt.windows.WPrinterJob, hibernate.connection.password=****, sun.os.patch.level=, hibernate.boot.CfgXmlAccessService.key=org.hibernate.reactive.provider.ReactiveServiceRegistryBuilder$1@18f8a270, java.library.path=C:\Program Files\Java\jdk1.8.0_241\bin;C:\windows\Sun\Java\bin;C:\windows\system32;C:\windows;C:\Program Files\AdoptOpenJDK\jdk-11.0.9.11-openj9\bin;C:\Python27\;C:\Python27\Scripts;C:\Program Files (x86)\Common Files\Oracle\Java\javapath;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\windows\System32\OpenSSH\;C:\Program Files\doxygen\bin;C:\Program Files\Git\cmd;C:\Program Files\Git\mingw64\libexec\git-core;C:\Program Files\Git\usr\bin;C:\Program Files\Amazon\AWSCLIV2\;C:\ProgramData\chocolatey\bin;C:\Program Files\TortoiseGit\bin;C:\Program Files\nodejs\;C:\Program Files\PuTTY\;C:\Users\yaakov.berkovitch\AppData\Local\Microsoft\WindowsApps;C:\Users\yaakov.berkovitch\AppData\Local\atom\bin;C:\apache-maven-3.6.3-bin\apache-maven-3.6.3\bin;C:\Program Files\Java\jdk1.8.0_241\bin;C:\Users\yaakov.berkovitch\AppData\Roaming\npm;., java.vendor=Oracle Corporation, java.vm.info=mixed mode, java.vm.version=25.241-b07, hibernate.bytecode.use_reflection_optimizer=false, sun.io.unicode.encoding=UnicodeLittle, java.ext.dirs=C:\Program Files\Java\jdk1.8.0_241\jre\lib\ext;C:\windows\Sun\Java\lib\ext, javax.persistence.jdbc.password=****, java.class.version=52.0}
18:42:30.945 [Test worker] DEBUG org.hibernate.secure.spi.JaccIntegrator - Skipping JACC integration as it was not enabled
18:42:30.946 [Test worker] INFO org.hibernate.reactive.provider.impl.ReactiveIntegrator - HRX000001: Hibernate Reactive Preview
18:42:30.951 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveAutoFlushEventListener@72a05793`] for match against existing listener [`org.hibernate.event.internal.DefaultAutoFlushEventListener@4240ecb6`]
18:42:30.951 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveAutoFlushEventListener@72a05793`] for match against existing listener [`org.hibernate.event.internal.DefaultAutoFlushEventListener@4240ecb6`]
18:42:30.951 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveAutoFlushEventListener@72a05793` and `org.hibernate.event.internal.DefaultAutoFlushEventListener@4240ecb6`
18:42:30.952 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultAutoFlushEventListener@4240ecb6` -> org.hibernate.reactive.event.impl.DefaultReactiveAutoFlushEventListener@72a05793
18:42:30.954 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveFlushEventListener@76a86f5a`] for match against existing listener [`org.hibernate.event.internal.DefaultFlushEventListener@1dc4cc07`]
18:42:30.954 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveFlushEventListener@76a86f5a`] for match against existing listener [`org.hibernate.event.internal.DefaultFlushEventListener@1dc4cc07`]
18:42:30.954 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveFlushEventListener@76a86f5a` and `org.hibernate.event.internal.DefaultFlushEventListener@1dc4cc07`
18:42:30.954 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultFlushEventListener@1dc4cc07` -> org.hibernate.reactive.event.impl.DefaultReactiveFlushEventListener@76a86f5a
18:42:30.957 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveFlushEntityEventListener@37fa54ee`] for match against existing listener [`org.hibernate.event.internal.DefaultFlushEntityEventListener@ce512e2`]
18:42:30.957 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveFlushEntityEventListener@37fa54ee`] for match against existing listener [`org.hibernate.event.internal.DefaultFlushEntityEventListener@ce512e2`]
18:42:30.957 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveFlushEntityEventListener@37fa54ee` and `org.hibernate.event.internal.DefaultFlushEntityEventListener@ce512e2`
18:42:30.957 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultFlushEntityEventListener@ce512e2` -> org.hibernate.reactive.event.impl.DefaultReactiveFlushEntityEventListener@37fa54ee
18:42:30.963 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactivePersistEventListener@435767e3`] for match against existing listener [`org.hibernate.event.internal.DefaultPersistEventListener@2281ebc0`]
18:42:30.964 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactivePersistEventListener@435767e3`] for match against existing listener [`org.hibernate.event.internal.DefaultPersistEventListener@2281ebc0`]
18:42:30.964 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactivePersistEventListener@435767e3` and `org.hibernate.event.internal.DefaultPersistEventListener@2281ebc0`
18:42:30.964 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultPersistEventListener@2281ebc0` -> org.hibernate.reactive.event.impl.DefaultReactivePersistEventListener@435767e3
18:42:30.964 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactivePersistOnFlushEventListener@38fbd8d3`] for match against existing listener [`org.hibernate.event.internal.DefaultPersistOnFlushEventListener@1509ebe7`]
18:42:30.964 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactivePersistOnFlushEventListener@38fbd8d3`] for match against existing listener [`org.hibernate.event.internal.DefaultPersistOnFlushEventListener@1509ebe7`]
18:42:30.964 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactivePersistOnFlushEventListener@38fbd8d3` and `org.hibernate.event.internal.DefaultPersistOnFlushEventListener@1509ebe7`
18:42:30.964 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultPersistOnFlushEventListener@1509ebe7` -> org.hibernate.reactive.event.impl.DefaultReactivePersistOnFlushEventListener@38fbd8d3
18:42:30.967 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveMergeEventListener@1589d18e`] for match against existing listener [`org.hibernate.event.internal.DefaultMergeEventListener@47c486a0`]
18:42:30.968 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveMergeEventListener@1589d18e`] for match against existing listener [`org.hibernate.event.internal.DefaultMergeEventListener@47c486a0`]
18:42:30.968 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveMergeEventListener@1589d18e` and `org.hibernate.event.internal.DefaultMergeEventListener@47c486a0`
18:42:30.968 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultMergeEventListener@47c486a0` -> org.hibernate.reactive.event.impl.DefaultReactiveMergeEventListener@1589d18e
18:42:30.971 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveDeleteEventListener@3dd26a78`] for match against existing listener [`org.hibernate.event.internal.DefaultDeleteEventListener@45d541fe`]
18:42:30.971 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveDeleteEventListener@3dd26a78`] for match against existing listener [`org.hibernate.event.internal.DefaultDeleteEventListener@45d541fe`]
18:42:30.971 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveDeleteEventListener@3dd26a78` and `org.hibernate.event.internal.DefaultDeleteEventListener@45d541fe`
18:42:30.971 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultDeleteEventListener@45d541fe` -> org.hibernate.reactive.event.impl.DefaultReactiveDeleteEventListener@3dd26a78
18:42:30.973 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveRefreshEventListener@338f5ca7`] for match against existing listener [`org.hibernate.event.internal.DefaultRefreshEventListener@65ba888e`]
18:42:30.974 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveRefreshEventListener@338f5ca7`] for match against existing listener [`org.hibernate.event.internal.DefaultRefreshEventListener@65ba888e`]
18:42:30.974 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveRefreshEventListener@338f5ca7` and `org.hibernate.event.internal.DefaultRefreshEventListener@65ba888e`
18:42:30.974 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultRefreshEventListener@65ba888e` -> org.hibernate.reactive.event.impl.DefaultReactiveRefreshEventListener@338f5ca7
18:42:30.975 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveLockEventListener@c67a90f`] for match against existing listener [`org.hibernate.event.internal.DefaultLockEventListener@66076fca`]
18:42:30.975 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveLockEventListener@c67a90f`] for match against existing listener [`org.hibernate.event.internal.DefaultLockEventListener@66076fca`]
18:42:30.975 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveLockEventListener@c67a90f` and `org.hibernate.event.internal.DefaultLockEventListener@66076fca`
18:42:30.975 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultLockEventListener@66076fca` -> org.hibernate.reactive.event.impl.DefaultReactiveLockEventListener@c67a90f
18:42:30.981 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveLoadEventListener@7721e287`] for match against existing listener [`org.hibernate.event.internal.DefaultLoadEventListener@60b3552`]
18:42:30.981 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveLoadEventListener@7721e287`] for match against existing listener [`org.hibernate.event.internal.DefaultLoadEventListener@60b3552`]
18:42:30.981 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveLoadEventListener@7721e287` and `org.hibernate.event.internal.DefaultLoadEventListener@60b3552`
18:42:30.981 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultLoadEventListener@60b3552` -> org.hibernate.reactive.event.impl.DefaultReactiveLoadEventListener@7721e287
18:42:30.981 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveInitializeCollectionEventListener@736bca6e`] for match against existing listener [`org.hibernate.event.internal.DefaultInitializeCollectionEventListener@2c15c0f4`]
18:42:30.981 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveInitializeCollectionEventListener@736bca6e`] for match against existing listener [`org.hibernate.event.internal.DefaultInitializeCollectionEventListener@2c15c0f4`]
18:42:30.982 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveInitializeCollectionEventListener@736bca6e` and `org.hibernate.event.internal.DefaultInitializeCollectionEventListener@2c15c0f4`
18:42:30.982 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultInitializeCollectionEventListener@2c15c0f4` -> org.hibernate.reactive.event.impl.DefaultReactiveInitializeCollectionEventListener@736bca6e
18:42:30.982 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactivePostLoadEventListener@7cc10027`] for match against existing listener [`org.hibernate.event.internal.DefaultPostLoadEventListener@7ed16d2b`]
18:42:30.982 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactivePostLoadEventListener@7cc10027`] for match against existing listener [`org.hibernate.event.internal.DefaultPostLoadEventListener@7ed16d2b`]
18:42:30.982 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactivePostLoadEventListener@7cc10027` and `org.hibernate.event.internal.DefaultPostLoadEventListener@7ed16d2b`
18:42:30.983 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultPostLoadEventListener@7ed16d2b` -> org.hibernate.reactive.event.impl.DefaultReactivePostLoadEventListener@7cc10027
18:42:30.984 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveResolveNaturalIdEventListener@283437c3`] for match against existing listener [`org.hibernate.event.internal.DefaultResolveNaturalIdEventListener@7a8c6cbe`]
18:42:30.984 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveResolveNaturalIdEventListener@283437c3`] for match against existing listener [`org.hibernate.event.internal.DefaultResolveNaturalIdEventListener@7a8c6cbe`]
18:42:30.984 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveResolveNaturalIdEventListener@283437c3` and `org.hibernate.event.internal.DefaultResolveNaturalIdEventListener@7a8c6cbe`
18:42:30.985 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultResolveNaturalIdEventListener@7a8c6cbe` -> org.hibernate.reactive.event.impl.DefaultReactiveResolveNaturalIdEventListener@283437c3
18:42:30.986 [Test worker] DEBUG org.hibernate.internal.SessionFactoryImpl - Instantiated session factory
18:42:30.987 [Test worker] DEBUG org.hibernate.type.spi.TypeConfiguration$Scope - Scoping TypeConfiguration [org.hibernate.type.spi.TypeConfiguration@201603dd] to SessionFactoryImpl [org.hibernate.reactive.session.impl.ReactiveSessionFactoryImpl@3a7290f]
18:42:31.076 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - Not known whether passed class name [model.Employee] is safe
18:42:31.076 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - No temp ClassLoader provided; using live ClassLoader for loading potentially unsafe class : model.Employee
18:42:31.374 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - Not known whether passed class name [model.Project] is safe
18:42:31.374 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - No temp ClassLoader provided; using live ClassLoader for loading potentially unsafe class : model.Project
18:42:31.410 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Static SQL for collection: model.Employee.projects
18:42:31.410 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  Row insert: insert into Employee_Project (employee_id, project_id) values ($1, $2)
18:42:31.410 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  Row update: update Employee_Project set project_id=$1 where employee_id=$2 and project_id=$3
18:42:31.410 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  Row delete: delete from Employee_Project where employee_id=$1 and project_id=$2
18:42:31.410 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  One-shot delete: delete from Employee_Project where employee_id=$1
18:42:31.411 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Static SQL for collection: model.Project.employees
18:42:31.411 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  Row insert: insert into Employee_Project (project_id, employee_id) values ($1, $2)
18:42:31.411 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  Row update: update Employee_Project set employee_id=$1 where project_id=$2 and employee_id=$3
18:42:31.411 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  Row delete: delete from Employee_Project where project_id=$1 and employee_id=$2
18:42:31.411 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  One-shot delete: delete from Employee_Project where project_id=$1
18:42:31.415 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Static SQL for entity: model.Employee
18:42:31.415 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Version select: select employee_id from Employee where employee_id =$1
18:42:31.415 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Snapshot select: select employee_.employee_id, employee_.first_name as first_na2_0_, employee_.last_name as last_nam3_0_ from Employee employee_ where employee_.employee_id=$1
18:42:31.416 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Insert 0: insert into Employee (first_name, last_name, employee_id) values ($1, $2, $3)
18:42:31.416 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Update 0: update Employee set first_name=$1, last_name=$2 where employee_id=$3
18:42:31.416 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Delete 0: delete from Employee where employee_id=$1
18:42:31.459 [Test worker] DEBUG org.hibernate.loader.plan.build.internal.spaces.QuerySpacesImpl - Adding QuerySpace : uid = <gen:0> -> org.hibernate.loader.plan.build.internal.spaces.EntityQuerySpaceImpl@3437c975]
18:42:31.460 [Test worker] DEBUG org.hibernate.persister.walking.spi.MetamodelGraphWalker - Visiting attribute path : firstName
18:42:31.460 [Test worker] DEBUG org.hibernate.persister.walking.spi.MetamodelGraphWalker - Visiting attribute path : lastName
18:42:31.461 [Test worker] DEBUG org.hibernate.persister.walking.spi.MetamodelGraphWalker - Visiting attribute path : projects
18:42:31.466 [Test worker] DEBUG org.hibernate.loader.plan.build.internal.spaces.QuerySpacesImpl - Adding QuerySpace : uid = <gen:1> -> org.hibernate.loader.plan.build.internal.spaces.CollectionQuerySpaceImpl@495fcde1]
18:42:31.468 [Test worker] DEBUG org.hibernate.loader.plan.build.internal.spaces.QuerySpacesImpl - Adding QuerySpace : uid = <gen:2> -> org.hibernate.loader.plan.build.internal.spaces.EntityQuerySpaceImpl@7861f587]
18:42:31.469 [Test worker] DEBUG org.hibernate.loader.plan.build.internal.FetchStyleLoadPlanBuildingAssociationVisitationStrategy - Building LoadPlan...
18:42:31.483 [Test worker] DEBUG org.hibernate.loader.plan.exec.internal.LoadQueryJoinAndFetchProcessor - processing queryspace <gen:0>
18:42:31.488 [Test worker] DEBUG org.hibernate.loader.plan.build.spi.LoadPlanTreePrinter - LoadPlan(entity=model.Employee)
    - Returns
       - EntityReturnImpl(entity=model.Employee, querySpaceUid=<gen:0>, path=model.Employee)
          - CollectionAttributeFetchImpl(collection=model.Employee.projects, querySpaceUid=<gen:1>, path=model.Employee.projects)
             - (collection element) CollectionFetchableElementEntityGraph(entity=model.Project, querySpaceUid=<gen:2>, path=model.Employee.projects.<elements>)
    - QuerySpaces
       - EntityQuerySpaceImpl(uid=<gen:0>, entity=model.Employee)
          - SQL table alias mapping - employee0_
          - alias suffix - 0_
          - suffixed key columns - {employee1_0_0_}

18:42:31.493 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Static SQL for entity: model.Project
18:42:31.493 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Version select: select project_id from Project where project_id =$1
18:42:31.493 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Snapshot select: select project_.project_id, project_.title as title2_2_ from Project project_ where project_.project_id=$1
18:42:31.493 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Insert 0: insert into Project (title, project_id) values ($1, $2)
18:42:31.493 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Update 0: update Project set title=$1 where project_id=$2
18:42:31.493 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Delete 0: delete from Project where project_id=$1
18:42:31.493 [Test worker] DEBUG org.hibernate.loader.plan.build.internal.spaces.QuerySpacesImpl - Adding QuerySpace : uid = <gen:0> -> org.hibernate.loader.plan.build.internal.spaces.EntityQuerySpaceImpl@3526b2c3]
18:42:31.493 [Test worker] DEBUG org.hibernate.persister.walking.spi.MetamodelGraphWalker - Visiting attribute path : employees
18:42:31.493 [Test worker] DEBUG org.hibernate.loader.plan.build.internal.spaces.QuerySpacesImpl - Adding QuerySpace : uid = <gen:1> -> org.hibernate.loader.plan.build.internal.spaces.CollectionQuerySpaceImpl@46b0e446]
18:42:31.493 [Test worker] DEBUG org.hibernate.loader.plan.build.internal.spaces.QuerySpacesImpl - Adding QuerySpace : uid = <gen:2> -> org.hibernate.loader.plan.build.internal.spaces.EntityQuerySpaceImpl@2a4a8a03]
18:42:31.493 [Test worker] DEBUG org.hibernate.persister.walking.spi.MetamodelGraphWalker - Visiting attribute path : title
18:42:31.493 [Test worker] DEBUG org.hibernate.loader.plan.build.internal.FetchStyleLoadPlanBuildingAssociationVisitationStrategy - Building LoadPlan...
18:42:31.494 [Test worker] DEBUG org.hibernate.loader.plan.exec.internal.LoadQueryJoinAndFetchProcessor - processing queryspace <gen:0>
18:42:31.494 [Test worker] DEBUG org.hibernate.loader.plan.build.spi.LoadPlanTreePrinter - LoadPlan(entity=model.Project)
    - Returns
       - EntityReturnImpl(entity=model.Project, querySpaceUid=<gen:0>, path=model.Project)
          - CollectionAttributeFetchImpl(collection=model.Project.employees, querySpaceUid=<gen:1>, path=model.Project.employees)
             - (collection element) CollectionFetchableElementEntityGraph(entity=model.Employee, querySpaceUid=<gen:2>, path=model.Project.employees.<elements>)
    - QuerySpaces
       - EntityQuerySpaceImpl(uid=<gen:0>, entity=model.Project)
          - SQL table alias mapping - project0_
          - alias suffix - 0_
          - suffixed key columns - {project_1_2_0_}

18:42:31.512 [Test worker] DEBUG org.hibernate.loader.Loader - Static select for one-to-many model.Employee.projects: select projects0_.employee_id as employee1_1_1_, projects0_.project_id as project_2_1_1_, project1_.project_id as project_1_2_0_, project1_.title as title2_2_0_ from Employee_Project projects0_ inner join Project project1_ on projects0_.project_id=project1_.project_id where projects0_.employee_id=$1
18:42:31.513 [Test worker] DEBUG org.hibernate.loader.Loader - Static select for one-to-many model.Project.employees: select employees0_.project_id as project_2_1_1_, employees0_.employee_id as employee1_1_1_, employee1_.employee_id as employee1_0_0_, employee1_.first_name as first_na2_0_0_, employee1_.last_name as last_nam3_0_0_ from Employee_Project employees0_ inner join Employee employee1_ on employees0_.employee_id=employee1_.employee_id where employees0_.project_id=$1
18:42:31.551 [Test worker] DEBUG org.hibernate.tool.schema.spi.SchemaManagementToolCoordinator - No actions specified; doing nothing
18:42:31.554 [Test worker] DEBUG org.hibernate.query.spi.NamedQueryRepository - Checking 0 named HQL queries
18:42:31.554 [Test worker] DEBUG org.hibernate.query.spi.NamedQueryRepository - Checking 0 named SQL queries
18:42:31.569 [Test worker] DEBUG org.hibernate.internal.SessionFactoryRegistry - Initializing SessionFactoryRegistry : org.hibernate.internal.SessionFactoryRegistry@28cc9148
18:42:31.572 [Test worker] DEBUG org.hibernate.internal.SessionFactoryRegistry - Registering SessionFactory: 55115020-cd59-4103-80e9-882e8cb5adb8 (<unnamed>)
18:42:31.572 [Test worker] DEBUG org.hibernate.internal.SessionFactoryRegistry - Not binding SessionFactory to JNDI, no JNDI name configured
18:42:31.603 [Test worker] DEBUG io.vertx.core.logging.LoggerFactory - Using io.vertx.core.logging.SLF4JLogDelegateFactory
18:42:31.644 [Test worker] DEBUG io.netty.util.internal.logging.InternalLoggerFactory - Using SLF4J as the default logging framework
18:42:31.645 [Test worker] DEBUG io.netty.util.internal.PlatformDependent - Platform: Windows
18:42:31.648 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - -Dio.netty.noUnsafe: false
18:42:31.648 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - Java version: 8
18:42:31.650 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - sun.misc.Unsafe.theUnsafe: available
18:42:31.651 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - sun.misc.Unsafe.copyMemory: available
18:42:31.652 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - java.nio.Buffer.address: available
18:42:31.653 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - direct buffer constructor: available
18:42:31.654 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - java.nio.Bits.unaligned: available, true
18:42:31.654 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - jdk.internal.misc.Unsafe.allocateUninitializedArray(int): unavailable prior to Java9
18:42:31.654 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - java.nio.DirectByteBuffer.<init>(long, int): available
18:42:31.654 [Test worker] DEBUG io.netty.util.internal.PlatformDependent - sun.misc.Unsafe: available
18:42:31.655 [Test worker] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.tmpdir: C:\Users\YAAKOV~1.BER\AppData\Local\Temp (java.io.tmpdir)
18:42:31.655 [Test worker] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.bitMode: 64 (sun.arch.data.model)
18:42:31.657 [Test worker] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.maxDirectMemory: 510656512 bytes
18:42:31.657 [Test worker] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.uninitializedArrayAllocationThreshold: -1
18:42:31.658 [Test worker] DEBUG io.netty.util.internal.CleanerJava6 - java.nio.ByteBuffer.cleaner(): available
18:42:31.658 [Test worker] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.noPreferDirect: false
18:42:31.683 [Test worker] DEBUG io.netty.util.ResourceLeakDetector - -Dio.netty.leakDetection.level: simple
18:42:31.683 [Test worker] DEBUG io.netty.util.ResourceLeakDetector - -Dio.netty.leakDetection.targetRecords: 4
18:42:31.712 [Test worker] DEBUG io.netty.channel.MultithreadEventLoopGroup - -Dio.netty.eventLoopThreads: 24
18:42:31.730 [Test worker] DEBUG io.netty.util.internal.InternalThreadLocalMap - -Dio.netty.threadLocalMap.stringBuilder.initialSize: 1024
18:42:31.730 [Test worker] DEBUG io.netty.util.internal.InternalThreadLocalMap - -Dio.netty.threadLocalMap.stringBuilder.maxSize: 4096
18:42:31.740 [Test worker] DEBUG io.netty.channel.nio.NioEventLoop - -Dio.netty.noKeySetOptimization: false
18:42:31.740 [Test worker] DEBUG io.netty.channel.nio.NioEventLoop - -Dio.netty.selectorAutoRebuildThreshold: 512
18:42:31.749 [Test worker] DEBUG io.netty.util.internal.PlatformDependent - org.jctools-core.MpscChunkedArrayQueue: available
18:42:31.795 [Test worker] DEBUG io.netty.resolver.dns.DefaultDnsServerAddressStreamProvider - Default DNS servers: [/192.168.11.20:53, /192.168.11.21:53, /10.254.254.1:53, /8.8.8.8:53, /208.91.112.53:53, /208.91.112.52:53] (sun.net.dns.ResolverConfiguration)
18:42:31.800 [Test worker] DEBUG io.netty.util.NetUtil - -Djava.net.preferIPv4Stack: false
18:42:31.800 [Test worker] DEBUG io.netty.util.NetUtil - -Djava.net.preferIPv6Addresses: false
18:42:32.141 [Test worker] DEBUG io.netty.util.NetUtilInitializations - Loopback interface: lo (Software Loopback Interface 1, 127.0.0.1)
18:42:32.143 [Test worker] DEBUG io.netty.util.NetUtil - Failed to get SOMAXCONN from sysctl and file \proc\sys\net\core\somaxconn. Default: 200
18:42:32.176 [Test worker] INFO org.hibernate.reactive.pool.impl.DefaultSqlClientPool - HRX000011: SQL Client URL [jdbc:postgresql://10.20.14.3:5432/demo]
18:42:32.184 [Test worker] INFO org.hibernate.reactive.pool.impl.DefaultSqlClientPool - HRX000012: Connection pool size: 5
18:42:32.301 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.numHeapArenas: 4
18:42:32.301 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.numDirectArenas: 5
18:42:32.301 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.pageSize: 8192
18:42:32.301 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.maxOrder: 11
18:42:32.301 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.chunkSize: 16777216
18:42:32.301 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.smallCacheSize: 256
18:42:32.301 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.normalCacheSize: 64
18:42:32.301 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.maxCachedBufferCapacity: 32768
18:42:32.301 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.cacheTrimInterval: 8192
18:42:32.301 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.cacheTrimIntervalMillis: 0
18:42:32.301 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.useCacheForAllThreads: true
18:42:32.301 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.maxCachedByteBuffersPerChunk: 1023
18:42:32.349 [vert.x-eventloop-thread-0] DEBUG io.netty.channel.DefaultChannelId - -Dio.netty.processId: 17468 (auto-detected)
18:42:32.732 [vert.x-eventloop-thread-0] DEBUG io.netty.channel.DefaultChannelId - -Dio.netty.machineId: 80:6d:97:ff:fe:00:be:eb (auto-detected)
18:42:32.758 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.ByteBufUtil - -Dio.netty.allocator.type: pooled
18:42:32.758 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.ByteBufUtil - -Dio.netty.threadLocalDirectBufferSize: 0
18:42:32.758 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.ByteBufUtil - -Dio.netty.maxThreadLocalCharBufferSize: 16384
18:42:33.210 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.AbstractByteBuf - -Dio.netty.buffer.checkAccessible: true
18:42:33.210 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.AbstractByteBuf - -Dio.netty.buffer.checkBounds: true
18:42:33.211 [vert.x-eventloop-thread-0] DEBUG io.netty.util.ResourceLeakDetectorFactory - Loaded default ResourceLeakDetector: io.netty.util.ResourceLeakDetector@3c45e7b6
18:42:33.216 [vert.x-eventloop-thread-0] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.maxCapacityPerThread: 4096
18:42:33.216 [vert.x-eventloop-thread-0] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.maxSharedCapacityFactor: 2
18:42:33.216 [vert.x-eventloop-thread-0] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.linkCapacity: 16
18:42:33.216 [vert.x-eventloop-thread-0] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.ratio: 8
18:42:33.216 [vert.x-eventloop-thread-0] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.delayedQueue.ratio: 8
18:42:33.310 [vert.x-eventloop-thread-0] DEBUG org.hibernate.stat.internal.StatisticsInitiator - Statistics initialized [enabled=false]
18:42:33.351 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - select nextval ('objects_id_seq')
18:42:33.442 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - select nextval ('objects_id_seq')
18:42:33.448 [vert.x-eventloop-thread-0] DEBUG org.hibernate.reactive.event.impl.AbstractReactiveFlushingEventListener - Processing flush-time cascades
18:42:33.453 [vert.x-eventloop-thread-0] DEBUG org.hibernate.reactive.event.impl.AbstractReactiveFlushingEventListener - Dirty checking collections
18:42:33.459 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.internal.Collections - Collection found: [model.Employee.projects#11], was: [<unreferenced>] (initialized)
18:42:33.460 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.internal.Collections - Collection found: [model.Project.employees#12], was: [<unreferenced>] (initialized)
18:42:33.460 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.internal.Collections - Collection found: [model.Project.employees#13], was: [<unreferenced>] (initialized)
18:42:33.460 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.internal.Collections - Collection found: [model.Employee.projects#12], was: [<unreferenced>] (initialized)
18:42:33.460 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.internal.Collections - Collection found: [model.Employee.projects#13], was: [<unreferenced>] (initialized)
18:42:33.460 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.internal.Collections - Collection found: [model.Employee.projects#14], was: [<unreferenced>] (initialized)
18:42:33.460 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.internal.Collections - Collection found: [model.Employee.projects#15], was: [<unreferenced>] (initialized)
18:42:33.461 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.internal.Collections - Collection found: [model.Employee.projects#16], was: [<unreferenced>] (initialized)
18:42:33.474 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee (first_name, last_name, employee_id) values ($1, $2, $3)
18:42:33.715 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.transaction.internal.TransactionImpl - On TransactionImpl creation, JpaCompliance#isJpaTransactionComplianceEnabled == false
Caught exception - exception = javax.persistence.PersistenceException: org.hibernate.HibernateException: io.vertx.pgclient.PgException: { "message": "duplicate key value violates unique constraint \"employee_pkey\"", "severity": "ERROR", "code": "23505", "detail": "Key (employee_id)=(11) already exists.", "file": "nbtinsert.c", "line": "570", "routine": "_bt_check_unique", "schema": "public", "table": "employee", "constraint": "employee_pkey" }
employee name = Oven, name = Oven
employee name = Norman, name = Norman
employee name = Dylan, name = Dylan
employee name = Borg, name = Borg
employee name = Regan, name = Regan
employee name = Kenedy, name = Kenedy
BUILD SUCCESSFUL in 8s
5 actionable tasks: 5 executed
6:42:34 PM: Task execution finished ':test --tests "ManyToManyIntegrationTest"'.

And this the output while using the default hibernate sequence:

> Task :compileJava
> Task :processResources UP-TO-DATE
> Task :classes
> Task :compileTestJava UP-TO-DATE
> Task :processTestResources UP-TO-DATE
> Task :testClasses UP-TO-DATE
> Task :test
18:44:45.879 [Test worker] DEBUG org.jboss.logging - Logging Provider: org.jboss.logging.Log4j2LoggerProvider
18:44:46.012 [Test worker] DEBUG org.hibernate.reactive.provider.ReactivePersistenceProvider - Located and parsed 1 persistence units; checking each
18:44:46.012 [Test worker] DEBUG org.hibernate.reactive.provider.ReactivePersistenceProvider - Checking persistence-unit [name=appviz-persistence-unit, explicit-provider=org.hibernate.reactive.provider.ReactivePersistenceProvider] against incoming persistence unit name [appviz-persistence-unit]
18:44:46.012 [Test worker] DEBUG org.hibernate.jpa.boot.spi.ProviderChecker - Persistence-unit [appviz-persistence-unit] requested PersistenceProvider [org.hibernate.reactive.provider.ReactivePersistenceProvider]
18:44:46.028 [Test worker] DEBUG org.hibernate.jpa.internal.util.LogHelper - PersistenceUnitInfo [
    name: appviz-persistence-unit
    persistence provider classname: org.hibernate.reactive.provider.ReactivePersistenceProvider
    classloader: null
    excludeUnlistedClasses: false
    JTA datasource: null
    Non JTA datasource: null
    Transaction type: RESOURCE_LOCAL
    PU root URL: file:/C:/dev/sequence-issue/build/resources/test/
    Shared Cache Mode: null
    Validation Mode: null
    Jar files URLs []
    Managed classes names [
        model.Employee
        model.Project]
    Mapping files names []
    Properties [
        javax.persistence.jdbc.driver: org.postgresql.Driver
        hibernate.jdbc.time_zone: UTC
        javax.persistence.jdbc.password: demo
        javax.persistence.schema-generation.database.motion: drop-and-create
        javax.persistence.sql-load-script-source: knowledge.sql
        javax.persistence.jdbc.url: jdbc:postgresql://10.20.14.3:5432/demo
        javax.persistence.jdbc.user: demo]
18:44:46.028 [Test worker] DEBUG org.hibernate.integrator.internal.IntegratorServiceImpl - Adding Integrator [org.hibernate.cfg.beanvalidation.BeanValidationIntegrator].
18:44:46.028 [Test worker] DEBUG org.hibernate.integrator.internal.IntegratorServiceImpl - Adding Integrator [org.hibernate.secure.spi.JaccIntegrator].
18:44:46.045 [Test worker] DEBUG org.hibernate.integrator.internal.IntegratorServiceImpl - Adding Integrator [org.hibernate.cache.internal.CollectionCacheInvalidator].
18:44:46.045 [Test worker] DEBUG org.hibernate.integrator.internal.IntegratorServiceImpl - Adding Integrator [org.hibernate.reactive.provider.impl.ReactiveIntegrator].
18:44:46.112 [Test worker] INFO org.hibernate.Version - HHH000412: Hibernate ORM core version 5.4.31.Final
18:44:46.112 [Test worker] DEBUG org.hibernate.cfg.Environment - HHH000206: hibernate.properties not found
18:44:46.312 [Test worker] DEBUG org.hibernate.service.spi.ServiceBinding - Overriding existing service binding [org.hibernate.secure.spi.JaccService]
18:44:46.345 [Test worker] DEBUG org.hibernate.cache.internal.RegionFactoryInitiator - Cannot default RegionFactory based on registered strategies as `[]` RegionFactory strategies were registered
18:44:46.345 [Test worker] DEBUG org.hibernate.cache.internal.RegionFactoryInitiator - Cache region factory : org.hibernate.cache.internal.NoCachingRegionFactory
18:44:46.378 [Test worker] INFO org.hibernate.annotations.common.Version - HCANN000001: Hibernate Commons Annotations {5.1.2.Final}
18:44:46.412 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration boolean -> org.hibernate.type.BooleanType@6dae4a1
18:44:46.412 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration boolean -> org.hibernate.type.BooleanType@6dae4a1
18:44:46.412 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Boolean -> org.hibernate.type.BooleanType@6dae4a1
18:44:46.412 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration numeric_boolean -> org.hibernate.type.NumericBooleanType@7f49b29f
18:44:46.427 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration true_false -> org.hibernate.type.TrueFalseType@3bc503b6
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration yes_no -> org.hibernate.type.YesNoType@6620f101
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration byte -> org.hibernate.type.ByteType@164a423
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration byte -> org.hibernate.type.ByteType@164a423
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Byte -> org.hibernate.type.ByteType@164a423
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration character -> org.hibernate.type.CharacterType@3e7f89f1
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration char -> org.hibernate.type.CharacterType@3e7f89f1
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Character -> org.hibernate.type.CharacterType@3e7f89f1
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration short -> org.hibernate.type.ShortType@784b3874
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration short -> org.hibernate.type.ShortType@784b3874
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Short -> org.hibernate.type.ShortType@784b3874
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration integer -> org.hibernate.type.IntegerType@4cc3f273
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration int -> org.hibernate.type.IntegerType@4cc3f273
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Integer -> org.hibernate.type.IntegerType@4cc3f273
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration long -> org.hibernate.type.LongType@1f57e4d4
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration long -> org.hibernate.type.LongType@1f57e4d4
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Long -> org.hibernate.type.LongType@1f57e4d4
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration float -> org.hibernate.type.FloatType@7ffac791
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration float -> org.hibernate.type.FloatType@7ffac791
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Float -> org.hibernate.type.FloatType@7ffac791
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration double -> org.hibernate.type.DoubleType@42376391
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration double -> org.hibernate.type.DoubleType@42376391
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Double -> org.hibernate.type.DoubleType@42376391
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration big_decimal -> org.hibernate.type.BigDecimalType@1f2d93c2
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.math.BigDecimal -> org.hibernate.type.BigDecimalType@1f2d93c2
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration big_integer -> org.hibernate.type.BigIntegerType@1551e5c0
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.math.BigInteger -> org.hibernate.type.BigIntegerType@1551e5c0
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration string -> org.hibernate.type.StringType@6393d0aa
18:44:46.428 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.String -> org.hibernate.type.StringType@6393d0aa
18:44:46.444 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration nstring -> org.hibernate.type.StringNVarcharType@7edb202b
18:44:46.445 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration ncharacter -> org.hibernate.type.CharacterNCharType@47f097
18:44:46.445 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration url -> org.hibernate.type.UrlType@1b0e3e12
18:44:46.445 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.net.URL -> org.hibernate.type.UrlType@1b0e3e12
18:44:46.445 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration Duration -> org.hibernate.type.DurationType@2e185e7f
18:44:46.445 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.time.Duration -> org.hibernate.type.DurationType@2e185e7f
18:44:46.445 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration Instant -> org.hibernate.type.InstantType@7fe059b
18:44:46.445 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.time.Instant -> org.hibernate.type.InstantType@7fe059b
18:44:46.445 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration LocalDateTime -> org.hibernate.type.LocalDateTimeType@27072afc
18:44:46.445 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.time.LocalDateTime -> org.hibernate.type.LocalDateTimeType@27072afc
18:44:46.461 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration LocalDate -> org.hibernate.type.LocalDateType@5f645cac
18:44:46.461 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.time.LocalDate -> org.hibernate.type.LocalDateType@5f645cac
18:44:46.461 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration LocalTime -> org.hibernate.type.LocalTimeType@1bee8c29
18:44:46.461 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.time.LocalTime -> org.hibernate.type.LocalTimeType@1bee8c29
18:44:46.461 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration OffsetDateTime -> org.hibernate.type.OffsetDateTimeType@a7f0866
18:44:46.461 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.time.OffsetDateTime -> org.hibernate.type.OffsetDateTimeType@a7f0866
18:44:46.461 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration OffsetTime -> org.hibernate.type.OffsetTimeType@2167694f
18:44:46.461 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.time.OffsetTime -> org.hibernate.type.OffsetTimeType@2167694f
18:44:46.498 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration ZonedDateTime -> org.hibernate.type.ZonedDateTimeType@2ce900c3
18:44:46.498 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.time.ZonedDateTime -> org.hibernate.type.ZonedDateTimeType@2ce900c3
18:44:46.498 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration date -> org.hibernate.type.DateType@6184416f
18:44:46.498 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.sql.Date -> org.hibernate.type.DateType@6184416f
18:44:46.498 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration time -> org.hibernate.type.TimeType@40202fd4
18:44:46.498 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.sql.Time -> org.hibernate.type.TimeType@40202fd4
18:44:46.498 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration timestamp -> org.hibernate.type.TimestampType@6a5b6342
18:44:46.498 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.sql.Timestamp -> org.hibernate.type.TimestampType@6a5b6342
18:44:46.498 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.util.Date -> org.hibernate.type.TimestampType@6a5b6342
18:44:46.512 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration dbtimestamp -> org.hibernate.type.DbTimestampType@6dc6909e
18:44:46.512 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration calendar -> org.hibernate.type.CalendarType@2750baf1
18:44:46.512 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.util.Calendar -> org.hibernate.type.CalendarType@2750baf1
18:44:46.512 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.util.GregorianCalendar -> org.hibernate.type.CalendarType@2750baf1
18:44:46.512 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration calendar_date -> org.hibernate.type.CalendarDateType@3b7f75ee
18:44:46.512 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration calendar_time -> org.hibernate.type.CalendarTimeType@279f25a3
18:44:46.512 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration locale -> org.hibernate.type.LocaleType@19c3a415
18:44:46.512 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.util.Locale -> org.hibernate.type.LocaleType@19c3a415
18:44:46.512 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration currency -> org.hibernate.type.CurrencyType@4e64ead9
18:44:46.512 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.util.Currency -> org.hibernate.type.CurrencyType@4e64ead9
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration timezone -> org.hibernate.type.TimeZoneType@2ec98d0
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.util.TimeZone -> org.hibernate.type.TimeZoneType@2ec98d0
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration class -> org.hibernate.type.ClassType@4ec0744f
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Class -> org.hibernate.type.ClassType@4ec0744f
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration uuid-binary -> org.hibernate.type.UUIDBinaryType@3ca9186
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.util.UUID -> org.hibernate.type.UUIDBinaryType@3ca9186
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration uuid-char -> org.hibernate.type.UUIDCharType@cc25aad
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration binary -> org.hibernate.type.BinaryType@1c3f528c
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration byte[] -> org.hibernate.type.BinaryType@1c3f528c
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration [B -> org.hibernate.type.BinaryType@1c3f528c
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration wrapper-binary -> org.hibernate.type.WrapperBinaryType@381e97c6
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration Byte[] -> org.hibernate.type.WrapperBinaryType@381e97c6
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration [Ljava.lang.Byte; -> org.hibernate.type.WrapperBinaryType@381e97c6
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration row_version -> org.hibernate.type.RowVersionType@6dd0da5b
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration image -> org.hibernate.type.ImageType@18582e4a
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration characters -> org.hibernate.type.CharArrayType@617c48d1
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration char[] -> org.hibernate.type.CharArrayType@617c48d1
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration [C -> org.hibernate.type.CharArrayType@617c48d1
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration wrapper-characters -> org.hibernate.type.CharacterArrayType@12cc2167
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration [Ljava.lang.Character; -> org.hibernate.type.CharacterArrayType@12cc2167
18:44:46.528 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration Character[] -> org.hibernate.type.CharacterArrayType@12cc2167
18:44:46.545 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration text -> org.hibernate.type.TextType@5d075c28
18:44:46.545 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration ntext -> org.hibernate.type.NTextType@677d1283
18:44:46.545 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration blob -> org.hibernate.type.BlobType@5557e80d
18:44:46.545 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.sql.Blob -> org.hibernate.type.BlobType@5557e80d
18:44:46.545 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration materialized_blob -> org.hibernate.type.MaterializedBlobType@5141099
18:44:46.545 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration clob -> org.hibernate.type.ClobType@1c88e20a
18:44:46.545 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.sql.Clob -> org.hibernate.type.ClobType@1c88e20a
18:44:46.545 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration nclob -> org.hibernate.type.NClobType@3ae5d87f
18:44:46.545 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.sql.NClob -> org.hibernate.type.NClobType@3ae5d87f
18:44:46.545 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration materialized_clob -> org.hibernate.type.MaterializedClobType@3075f617
18:44:46.545 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration materialized_nclob -> org.hibernate.type.MaterializedNClobType@7f68c3c4
18:44:46.545 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration serializable -> org.hibernate.type.SerializableType@15fdf3cf
18:44:46.545 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration object -> org.hibernate.type.ObjectType@27b15d9f
18:44:46.545 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.lang.Object -> org.hibernate.type.ObjectType@27b15d9f
18:44:46.560 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration imm_date -> org.hibernate.type.AdaptedImmutableType@8f697df
18:44:46.560 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration imm_time -> org.hibernate.type.AdaptedImmutableType@25867efb
18:44:46.560 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration imm_timestamp -> org.hibernate.type.AdaptedImmutableType@410e036e
18:44:46.560 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration imm_dbtimestamp -> org.hibernate.type.AdaptedImmutableType@e03d551
18:44:46.560 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration imm_calendar -> org.hibernate.type.AdaptedImmutableType@3277f321
18:44:46.560 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration imm_calendar_date -> org.hibernate.type.AdaptedImmutableType@24902232
18:44:46.562 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration imm_binary -> org.hibernate.type.AdaptedImmutableType@15b450bd
18:44:46.562 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration imm_serializable -> org.hibernate.type.AdaptedImmutableType@50db607
18:44:46.579 [Test worker] DEBUG org.hibernate.boot.internal.BootstrapContextImpl - Injecting ScanEnvironment [org.hibernate.jpa.boot.internal.StandardJpaScanEnvironmentImpl@3ba56b16] into BootstrapContext; was [null]
18:44:46.579 [Test worker] DEBUG org.hibernate.boot.internal.BootstrapContextImpl - Injecting ScanOptions [org.hibernate.boot.archive.scan.internal.StandardScanOptions@71dae496] into BootstrapContext; was [org.hibernate.boot.archive.scan.internal.StandardScanOptions@1e90c196]
18:44:46.612 [Test worker] DEBUG org.hibernate.boot.internal.BootstrapContextImpl - Injecting JPA temp ClassLoader [null] into BootstrapContext; was [null]
18:44:46.612 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - ClassLoaderAccessImpl#injectTempClassLoader(null) [was null]
18:44:46.628 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [uuid2] -> [org.hibernate.id.UUIDGenerator]
18:44:46.628 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [guid] -> [org.hibernate.id.GUIDGenerator]
18:44:46.628 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [uuid] -> [org.hibernate.id.UUIDHexGenerator]
18:44:46.628 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [uuid.hex] -> [org.hibernate.id.UUIDHexGenerator]
18:44:46.628 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [assigned] -> [org.hibernate.id.Assigned]
18:44:46.628 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [identity] -> [org.hibernate.id.IdentityGenerator]
18:44:46.628 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [select] -> [org.hibernate.id.SelectGenerator]
18:44:46.628 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [sequence] -> [org.hibernate.id.enhanced.SequenceStyleGenerator]
18:44:46.628 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [seqhilo] -> [org.hibernate.id.SequenceHiLoGenerator]
18:44:46.628 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [increment] -> [org.hibernate.id.IncrementGenerator]
18:44:46.628 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [foreign] -> [org.hibernate.id.ForeignGenerator]
18:44:46.628 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [sequence-identity] -> [org.hibernate.id.SequenceIdentityGenerator]
18:44:46.628 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [enhanced-sequence] -> [org.hibernate.id.enhanced.SequenceStyleGenerator]
18:44:46.628 [Test worker] DEBUG org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory - Registering IdentifierGenerator strategy [enhanced-table] -> [org.hibernate.id.enhanced.TableGenerator]
18:44:46.678 [Test worker] INFO org.hibernate.dialect.Dialect - HHH000400: Using dialect: org.hibernate.dialect.PostgreSQL10Dialect
18:44:46.698 [Test worker] DEBUG org.hibernate.engine.jdbc.env.spi.IdentifierHelperBuilder - IdentifierCaseStrategy for both quoted and unquoted identifiers was set to the same strategy [MIXED]; that will likely lead to problems in schema update and validation if using quoted identifiers
18:44:46.698 [Test worker] DEBUG org.hibernate.engine.jdbc.env.internal.LobCreatorBuilderImpl - HHH000422: Disabling contextual LOB creation as connection was null
18:44:46.712 [Test worker] DEBUG org.hibernate.boot.model.relational.Namespace - Created database namespace [logicalName=Name{catalog=null, schema=null}, physicalName=Name{catalog=null, schema=null}]
18:44:46.713 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration pg-uuid -> org.hibernate.type.PostgresUUIDType@2103bdd
18:44:46.713 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration java.util.UUID -> org.hibernate.type.PostgresUUIDType@2103bdd
18:44:46.713 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - HHH000270: Type registration [java.util.UUID] overrides previous : org.hibernate.type.UUIDBinaryType@3ca9186
18:44:46.713 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration materialized_blob -> org.hibernate.reactive.provider.impl.ReactiveTypeContributor$BlobType@5f9984c
18:44:46.713 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - HHH000270: Type registration [materialized_blob] overrides previous : org.hibernate.type.MaterializedBlobType@5141099
18:44:46.713 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - Adding type registration materialized_clob -> org.hibernate.reactive.provider.impl.ReactiveTypeContributor$ClobType@37cbcf28
18:44:46.713 [Test worker] DEBUG org.hibernate.type.BasicTypeRegistry - HHH000270: Type registration [materialized_clob] overrides previous : org.hibernate.type.MaterializedClobType@3075f617
18:44:46.713 [Test worker] DEBUG org.hibernate.type.spi.TypeConfiguration$Scope - Scoping TypeConfiguration [org.hibernate.type.spi.TypeConfiguration@380bb041] to MetadataBuildingContext [org.hibernate.boot.internal.MetadataBuildingContextRootImpl@2fbfb936]
18:44:46.798 [Test worker] WARN org.hibernate.cfg.AnnotationBinder - HHH000503: A class should not be annotated with both @Inheritance and @MappedSuperclass. @Inheritance will be ignored for: model.BaseEntity.
18:44:46.812 [Test worker] DEBUG org.hibernate.cfg.AnnotationBinder - Binding entity from annotated class: model.Employee
18:44:46.828 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3DiscriminatorColumn{logicalColumnName'DTYPE', discriminatorTypeName='string'}
18:44:46.828 [Test worker] DEBUG org.hibernate.cfg.annotations.EntityBinder - Import with entity name Employee
18:44:46.845 [Test worker] DEBUG org.hibernate.cfg.annotations.EntityBinder - Bind entity model.Employee on table Employee
18:44:46.878 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Column(s) overridden for property id
18:44:46.878 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Employee), mappingColumn=employee_id, insertable=true, updatable=true, unique=false}
18:44:46.878 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - Not known whether passed class name [model.Employee] is safe
18:44:46.878 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - No temp ClassLoader provided; using live ClassLoader for loading potentially unsafe class : model.Employee
18:44:46.878 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - MetadataSourceProcessor property id with lazy=false
18:44:46.878 [Test worker] DEBUG org.hibernate.cfg.AbstractPropertyHolder - Attempting to locate auto-apply AttributeConverter for property [model.Employee:id]
18:44:46.878 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - building SimpleValue for id
18:44:46.895 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - Building property id
18:44:46.898 [Test worker] DEBUG org.hibernate.cfg.BinderHelper - #makeIdGenerator(org.hibernate.mapping.SimpleValue([org.hibernate.mapping.Column(employee_id)]), id, org.hibernate.id.enhanced.SequenceStyleGenerator, , ...)
18:44:46.898 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Employee), mappingColumn=first_name, insertable=true, updatable=true, unique=false}
18:44:46.898 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - MetadataSourceProcessor property firstName with lazy=false
18:44:46.898 [Test worker] DEBUG org.hibernate.cfg.AbstractPropertyHolder - Attempting to locate auto-apply AttributeConverter for property [model.Employee:firstName]
18:44:46.898 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - building SimpleValue for firstName
18:44:46.898 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - Building property firstName
18:44:46.898 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Employee), mappingColumn=last_name, insertable=true, updatable=true, unique=false}
18:44:46.898 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - MetadataSourceProcessor property lastName with lazy=false
18:44:46.898 [Test worker] DEBUG org.hibernate.cfg.AbstractPropertyHolder - Attempting to locate auto-apply AttributeConverter for property [model.Employee:lastName]
18:44:46.898 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - building SimpleValue for lastName
18:44:46.898 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - Building property lastName
18:44:46.898 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Employee), mappingColumn=null, insertable=true, updatable=true, unique=false}
18:44:46.912 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Employee), mappingColumn=element, insertable=true, updatable=true, unique=false}
18:44:46.912 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Employee), mappingColumn=projects_KEY, insertable=true, updatable=true, unique=false}
18:44:46.912 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3JoinColumn{logicalColumnName='projects_KEY', referencedColumn='null', mappedBy='null'}
18:44:46.912 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3JoinColumn{logicalColumnName='employee_id', referencedColumn='', mappedBy=''}
18:44:46.912 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3JoinColumn{logicalColumnName='project_id', referencedColumn='', mappedBy=''}
18:44:46.912 [Test worker] DEBUG org.hibernate.cfg.annotations.CollectionBinder - Collection role: model.Employee.projects
18:44:46.912 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - Building property projects
18:44:46.927 [Test worker] DEBUG org.hibernate.cfg.AnnotationBinder - Binding entity from annotated class: model.Project
18:44:46.927 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3DiscriminatorColumn{logicalColumnName'DTYPE', discriminatorTypeName='string'}
18:44:46.927 [Test worker] DEBUG org.hibernate.cfg.annotations.EntityBinder - Import with entity name Project
18:44:46.927 [Test worker] DEBUG org.hibernate.cfg.annotations.EntityBinder - Bind entity model.Project on table Project
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Column(s) overridden for property id
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Project), mappingColumn=project_id, insertable=true, updatable=true, unique=false}
18:44:46.928 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - Not known whether passed class name [model.Project] is safe
18:44:46.928 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - No temp ClassLoader provided; using live ClassLoader for loading potentially unsafe class : model.Project
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - MetadataSourceProcessor property id with lazy=false
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.AbstractPropertyHolder - Attempting to locate auto-apply AttributeConverter for property [model.Project:id]
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - building SimpleValue for id
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - Building property id
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.BinderHelper - #makeIdGenerator(org.hibernate.mapping.SimpleValue([org.hibernate.mapping.Column(project_id)]), id, org.hibernate.id.enhanced.SequenceStyleGenerator, , ...)
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Project), mappingColumn=null, insertable=true, updatable=true, unique=false}
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Project), mappingColumn=element, insertable=true, updatable=true, unique=false}
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Project), mappingColumn=employees_KEY, insertable=true, updatable=true, unique=false}
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3JoinColumn{logicalColumnName='employees_KEY', referencedColumn='null', mappedBy='null'}
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3JoinColumn{logicalColumnName='null', referencedColumn='null', mappedBy='projects'}
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3JoinColumn{logicalColumnName='null', referencedColumn='null', mappedBy='projects'}
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.annotations.CollectionBinder - Collection role: model.Project.employees
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - Building property employees
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.Ejb3Column - Binding column: Ejb3Column{table=org.hibernate.mapping.Table(Project), mappingColumn=title, insertable=true, updatable=true, unique=false}
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - MetadataSourceProcessor property title with lazy=false
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.AbstractPropertyHolder - Attempting to locate auto-apply AttributeConverter for property [model.Project:title]
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - building SimpleValue for title
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.annotations.PropertyBinder - Building property title
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - Starting fillSimpleValue for id
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - Starting fillSimpleValue for firstName
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - Starting fillSimpleValue for lastName
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - Starting fillSimpleValue for id
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.annotations.SimpleValueBinder - Starting fillSimpleValue for title
18:44:46.928 [Test worker] DEBUG org.hibernate.mapping.PrimaryKey - Forcing column [employee_id] to be non-null as it is part of the primary key for table [employee]
18:44:46.928 [Test worker] DEBUG org.hibernate.mapping.PrimaryKey - Forcing column [project_id] to be non-null as it is part of the primary key for table [project]
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.CollectionSecondPass - Second pass for collection: model.Employee.projects
18:44:46.928 [Test worker] DEBUG org.hibernate.cfg.annotations.CollectionBinder - Binding as ManyToMany: model.Employee.projects
18:44:46.945 [Test worker] DEBUG org.hibernate.mapping.PrimaryKey - Forcing column [employee_id] to be non-null as it is part of the primary key for table [employee_project]
18:44:46.945 [Test worker] DEBUG org.hibernate.mapping.PrimaryKey - Forcing column [project_id] to be non-null as it is part of the primary key for table [employee_project]
18:44:46.945 [Test worker] DEBUG org.hibernate.cfg.CollectionSecondPass - Mapped collection key: employee_id, element: project_id
18:44:46.945 [Test worker] DEBUG org.hibernate.cfg.CollectionSecondPass - Second pass for collection: model.Project.employees
18:44:46.945 [Test worker] DEBUG org.hibernate.cfg.annotations.CollectionBinder - Binding as ManyToMany: model.Project.employees
18:44:46.945 [Test worker] DEBUG org.hibernate.cfg.annotations.TableBinder - Retrieving property model.Employee.projects
18:44:46.945 [Test worker] DEBUG org.hibernate.cfg.CollectionSecondPass - Mapped collection key: project_id, element: employee_id
18:44:46.945 [Test worker] DEBUG org.hibernate.boot.internal.InFlightMetadataCollectorImpl - Resolving reference to class: model.Project
18:44:46.945 [Test worker] DEBUG org.hibernate.boot.internal.InFlightMetadataCollectorImpl - Resolving reference to class: model.Employee
18:44:46.945 [Test worker] DEBUG org.hibernate.boot.internal.InFlightMetadataCollectorImpl - Resolving reference to class: model.Project
18:44:46.945 [Test worker] DEBUG org.hibernate.boot.internal.InFlightMetadataCollectorImpl - Resolving reference to class: model.Employee
18:44:47.028 [Test worker] DEBUG org.hibernate.internal.SessionFactoryImpl - Building session factory
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - SessionFactory name : null
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Automatic flush during beforeCompletion(): enabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Automatic session close at end of transaction: disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Statistics: disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Deleted entity synthetic identifier rollback: disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Default entity-mode: pojo
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Check Nullability in Core (should be disabled when Bean Validation is on): enabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Allow initialization of lazy state outside session : disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Using BatchFetchStyle : LEGACY
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Default batch fetch size: -1
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Maximum outer join fetch depth: null
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Default null ordering: NONE
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Order SQL updates by primary key: disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Order SQL inserts for batching: disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - multi-tenancy strategy : NONE
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - JTA Track by Thread: enabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Query language substitutions: {}
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Named query checking : enabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Second-level cache: disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Second-level query cache: disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Second-level query cache factory: null
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Second-level cache region prefix: null
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Optimize second-level cache for minimal puts: disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Structured second-level cache entries: disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Second-level cache direct-reference entries: disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Automatic eviction of collection cache: disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - JDBC batch size: 0
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - JDBC batch updates for versioned data: enabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Scrollable result sets: disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Wrap result sets: disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - JDBC3 getGeneratedKeys(): disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - JDBC result set fetch size: null
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Connection release mode: AFTER_TRANSACTION
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - Generate SQL with comments: disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - JPA compliance - query : disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - JPA compliance - closed-handling : disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - JPA compliance - lists : disabled
18:44:47.028 [Test worker] DEBUG org.hibernate.cfg.Settings - JPA compliance - transactions : disabled
18:44:47.128 [Test worker] DEBUG org.hibernate.internal.SessionFactoryImpl - Session factory constructed with filter configurations : {}
18:44:47.128 [Test worker] DEBUG org.hibernate.internal.SessionFactoryImpl - Instantiating session factory with properties: {sun.desktop=windows, awt.toolkit=sun.awt.windows.WToolkit, java.specification.version=1.8, file.encoding.pkg=sun.io, sun.cpu.isalist=amd64, sun.jnu.encoding=Cp1252, hibernate.dialect=org.hibernate.dialect.PostgreSQL10Dialect, org.gradle.test.worker=2, java.class.path=C:\dev\sequence-issue\build\classes\java\test;C:\dev\sequence-issue\build\resources\test;C:\dev\sequence-issue\build\classes\java\main;C:\dev\sequence-issue\build\resources\main;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework.boot\spring-boot-starter-webflux\2.4.5\45bd0ec9dd895e17d5e21f18ca0ca8ddce071e74\spring-boot-starter-webflux-2.4.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.vertx\vertx-web\4.0.3\5d0cec868aadac3e098b1ab6409dc2cebae83930\vertx-web-4.0.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.vertx\vertx-pg-client\4.0.3\3f4c6c885312b1b9aa73f66b939339c5d969a04b\vertx-pg-client-4.0.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.vertx\vertx-web-common\4.0.3\274167d895a6170335a5fd7e71d4519b9608d275\vertx-web-common-4.0.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.vertx\vertx-auth-common\4.0.3\177afb713c00713eff76a6cd83a8090c601af474\vertx-auth-common-4.0.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.hibernate.reactive\hibernate-reactive-core\1.0.0.CR4\bc9331436f75b8d2b73738fc4ab5c70676aaf96a\hibernate-reactive-core-1.0.0.CR4.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.vertx\vertx-sql-client\4.0.3\2bb64597f96e27a4a431967bea77592286d35462\vertx-sql-client-4.0.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.vertx\vertx-core\4.0.3\40c496ede9a948ec673df8cf5ab227ff853dd061\vertx-core-4.0.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework.boot\spring-boot-starter-json\2.4.5\5c1623aa53adf10cd98884f60db2d9f5958dbc28\spring-boot-starter-json-2.4.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.fasterxml.jackson.datatype\jackson-datatype-jsr310\2.11.4\ce6fc76bba06623720e5a9308386b6ae74753f4d\jackson-datatype-jsr310-2.11.4.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.fasterxml.jackson.core\jackson-annotations\2.12.3\7275513412694a1aafd08c0287f48469fa0e6e17\jackson-annotations-2.12.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.fasterxml.jackson.datatype\jackson-datatype-jdk8\2.11.4\e1540dea3c6c681ea4e335a960f730861ee3bedb\jackson-datatype-jdk8-2.11.4.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.fasterxml.jackson.module\jackson-module-parameter-names\2.11.4\432e050d79f2282a66c320375d628f1b0842cb12\jackson-module-parameter-names-2.11.4.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.fasterxml.jackson.core\jackson-databind\2.12.3\d6153f8fc60c479ab0f9efb35c034526436a4953\jackson-databind-2.12.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.fasterxml.jackson.core\jackson-core\2.12.3\deb23fe2a7f2b773e18ced2b50d4acc1df8fa366\jackson-core-2.12.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.hibernate\hibernate-core\5.4.31.Final\b0cd5c1d0dbaed84573203fd348a7e64f94d33cb\hibernate-core-5.4.31.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.junit.platform\junit-platform-engine\1.7.1\d276a968c57f5d60a421dedd1f8b6ca2fae09e86\junit-platform-engine-1.7.1.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.junit.jupiter\junit-jupiter-api\5.7.1\a7261dff44e64aea7f621842eac5977fd6d2412d\junit-jupiter-api-5.7.1.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.junit.platform\junit-platform-commons\1.7.1\7c49f0074842d07f4335de2389d624a7437d1407\junit-platform-commons-1.7.1.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.junit.jupiter\junit-jupiter-engine\5.7.1\7fcfa59c5533abb41f86b1408960737eeaf1f49f\junit-jupiter-engine-5.7.1.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework.boot\spring-boot-starter\2.4.5\5f5398f459beb958fc2ece1a433420bf03cec628\spring-boot-starter-2.4.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework.boot\spring-boot-starter-reactor-netty\2.4.5\727f61ad77ff370ffbc33761dd52a71eae2e4204\spring-boot-starter-reactor-netty-2.4.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework\spring-webflux\5.3.6\39370e45b105ae08298beb88a1a5d070995fe3dd\spring-webflux-5.3.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework\spring-web\5.3.6\f9290db7324194921c236ad9a940467f55304fa7\spring-web-5.3.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.projectreactor.netty\reactor-netty-http\1.0.6\6a76dd233bdb1e6dc5364ae1b5c8627c2cb0288f\reactor-netty-http-1.0.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.projectreactor.netty\reactor-netty-core\1.0.6\c24e7e989913d50c2ed592892e55ce1284364ac4\reactor-netty-core-1.0.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-handler-proxy\4.1.63.Final\d8cbdc537d75f219c04a057b984b2f0b55c1dbff\netty-handler-proxy-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-codec-http2\4.1.63.Final\294e90696d8d6e20c889511d2484b37158cb9caa\netty-codec-http2-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-codec-http\4.1.63.Final\f8c9b159dcb76452dc98a370a5511ff993670419\netty-codec-http-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-resolver-dns-native-macos\4.1.63.Final\d72f13b680d361b0e217704bda59ccb4b3256297\netty-resolver-dns-native-macos-4.1.63.Final-osx-x86_64.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-resolver-dns\4.1.63.Final\f44dc8ed52ff8528a88c157879c9baffe464fa46\netty-resolver-dns-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-handler\4.1.63.Final\879a43c2325b08e92e8967218b6ddb0ed4b7a0d3\netty-handler-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-codec-socks\4.1.63.Final\97707b764c9287836dcf626dd03c81f3bbfc86c6\netty-codec-socks-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-codec-dns\4.1.63.Final\91c445232b12e13bf6757579a39bab81e1233af5\netty-codec-dns-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-codec\4.1.63.Final\d4d2fccea88c80e56d59ce1053c53df0f9f4f5db\netty-codec-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-transport-native-epoll\4.1.63.Final\98d898362dd9a00eb23e2857a49cb15ff37353dc\netty-transport-native-epoll-4.1.63.Final-linux-x86_64.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-transport-native-unix-common\4.1.63.Final\85bd91382ec54b300ad3ff59efccbb4fccb22a88\netty-transport-native-unix-common-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-transport\4.1.63.Final\9a8bbe1ba082c9434e6f524d3864a53f340f2df\netty-transport-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-buffer\4.1.63.Final\40028ce5ac7c43f1c9a1439f74637cad04013e23\netty-buffer-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-resolver\4.1.63.Final\d07cd47c101dfa655d6d5cc304d523742fd78ca8\netty-resolver-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.netty\netty-common\4.1.63.Final\e1206b46384d4dcbecee2901f18ce65ecf02e8a4\netty-common-4.1.63.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.vertx\vertx-bridge-common\4.0.3\423970a10bdc4fa14ce4ad18ef3f45d703e53420\vertx-bridge-common-4.0.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.hibernate.common\hibernate-commons-annotations\5.1.2.Final\e59ffdbc6ad09eeb33507b39ffcf287679a498c8\hibernate-commons-annotations-5.1.2.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.jboss.logging\jboss-logging\3.4.1.Final\40fd4d696c55793e996d1ff3c475833f836c2498\jboss-logging-3.4.1.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.ibm.async\asyncutil\0.1.0\440941c382166029a299602e6c9ff5abde1b5143\asyncutil-0.1.0.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.smallrye.reactive\mutiny\0.16.0\c1b40622059bbb3f771317fe75af11c4cbdbb081\mutiny-0.16.0.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\javax.persistence\javax.persistence-api\2.2\25665ac8c0b62f50e6488173233239120fc52c96\javax.persistence-api-2.2.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.javassist\javassist\3.27.0-GA\f63e6aa899e15eca8fdaa402a79af4c417252213\javassist-3.27.0-GA.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\net.bytebuddy\byte-buddy\1.10.22\ef45d7e2cd1c600d279704f492ed5ce2ceb6cdb5\byte-buddy-1.10.22.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\antlr\antlr\2.7.7\83cd2cd674a217ade95a4bb83a8a14f351f48bd0\antlr-2.7.7.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.jboss.spec.javax.transaction\jboss-transaction-api_1.2_spec\1.1.1.Final\a8485cab9484dda36e9a8c319e76b5cc18797b58\jboss-transaction-api_1.2_spec-1.1.1.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.jboss\jandex\2.2.3.Final\d3865101f0666b63586683bd811d754517f331ab\jandex-2.2.3.Final.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.fasterxml\classmate\1.5.1\3fe0bed568c62df5e89f4f174c101eab25345b6c\classmate-1.5.1.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\javax.xml.bind\jaxb-api\2.3.1\8531ad5ac454cc2deb9d4d32c40c4d7451939b5d\jaxb-api-2.3.1.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\javax.activation\javax.activation-api\1.2.0\85262acf3ca9816f9537ca47d5adeabaead7cb16\javax.activation-api-1.2.0.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.dom4j\dom4j\2.1.3\a75914155a9f5808963170ec20653668a2ffd2fd\dom4j-2.1.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.glassfish.jaxb\jaxb-runtime\2.3.4\5f3828d3a345ff3d8acb83f07ba04eccf59e15bb\jaxb-runtime-2.3.4.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.apiguardian\apiguardian-api\1.1.0\fc9dff4bb36d627bdc553de77e1f17efd790876c\apiguardian-api-1.1.0.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework.boot\spring-boot-autoconfigure\2.4.5\32887531b5a125d4840a08f9610aec766de73606\spring-boot-autoconfigure-2.4.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework.boot\spring-boot\2.4.5\d9b82470df2e9549e448d7cef39e578d91f9867f\spring-boot-2.4.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework.boot\spring-boot-starter-logging\2.4.5\1537bbc2e8c6c9db806f9fa11ca7af394d7adacc\spring-boot-starter-logging-2.4.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\jakarta.annotation\jakarta.annotation-api\1.3.5\59eb84ee0d616332ff44aba065f3888cf002cd2d\jakarta.annotation-api-1.3.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework\spring-context\5.3.6\d99d3931528309a4ff49b7d18f76f1657aa16c52\spring-context-5.3.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework\spring-aop\5.3.6\510d9afcfce1fa9a2086906ab87b9989973c251c\spring-aop-5.3.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework\spring-beans\5.3.6\99cc944fb3454410b47fc98d4b148a6205bfe8f6\spring-beans-5.3.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework\spring-expression\5.3.6\33ba1d8dfaab0fd60ce4213a206f5d4fc77ce27a\spring-expression-5.3.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework\spring-core\5.3.6\8f91f60f628075701fde72bb5a43a33feeb27e93\spring-core-5.3.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.yaml\snakeyaml\1.27\359d62567480b07a679dc643f82fc926b100eed5\snakeyaml-1.27.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.projectreactor\reactor-core\3.4.5\c8adeb48f74b16c2dfb4f06a880820b9eaed50cf\reactor-core-3.4.5.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.reactivestreams\reactive-streams\1.0.3\d9fb7a7926ffa635b3dcaa5049fb2bfa25b3e7d0\reactive-streams-1.0.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\io.smallrye.common\smallrye-common-annotation\1.6.0\12f41ad4ec9bda7bb38beebb37a29910500b2fc4\smallrye-common-annotation-1.6.0.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\jakarta.xml.bind\jakarta.xml.bind-api\2.3.3\48e3b9cfc10752fba3521d6511f4165bea951801\jakarta.xml.bind-api-2.3.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.glassfish.jaxb\txw2\2.3.4\257fa649d3137a1060d222aefb96b7d1dd5f1286\txw2-2.3.4.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.sun.istack\istack-commons-runtime\3.0.12\cbbe1a62b0cc6c85972e99d52aaee350153dc530\istack-commons-runtime-3.0.12.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\com.sun.activation\jakarta.activation\1.2.2\74548703f9851017ce2f556066659438019e7eb5\jakarta.activation-1.2.2.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.opentest4j\opentest4j\1.2.0\28c11eb91f9b6d8e200631d46e20a7f407f2a046\opentest4j-1.2.0.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\ch.qos.logback\logback-classic\1.2.3\7c4f3c474fb2c041d8028740440937705ebb473a\logback-classic-1.2.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.apache.logging.log4j\log4j-to-slf4j\2.13.3\966f6fd1af4959d6b12bfa880121d4a2b164f857\log4j-to-slf4j-2.13.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.slf4j\jul-to-slf4j\1.7.30\d58bebff8cbf70ff52b59208586095f467656c30\jul-to-slf4j-1.7.30.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.springframework\spring-jcl\5.3.6\53ddfc5cfdf438a1d3346fc2912c3e3049a8cb76\spring-jcl-5.3.6.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\ch.qos.logback\logback-core\1.2.3\864344400c3d4d92dfeb0a305dc87d953677c03c\logback-core-1.2.3.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.slf4j\slf4j-api\1.7.30\b5a4b6d16ab13e34a88fae84c35cd5d68cac922c\slf4j-api-1.7.30.jar;C:\Users\yaakov.berkovitch\.gradle\caches\modules-2\files-2.1\org.apache.logging.log4j\log4j-api\2.13.3\ec1508160b93d274b1add34419b897bae84c6ca9\log4j-api-2.13.3.jar, javax.persistence.sql-load-script-source=knowledge.sql, java.vm.vendor=Oracle Corporation, sun.arch.data.model=64, user.variant=, java.vendor.url=http://java.oracle.com/, user.timezone=Asia/Jerusalem, javax.persistence.jdbc.url=jdbc:postgresql://10.20.14.3:5432/demo, javax.persistence.jdbc.user=****, hibernate.jdbc.time_zone=UTC, os.name=Windows 10, java.vm.specification.version=1.8, user.country=US, sun.java.launcher=SUN_STANDARD, local.setting.IS_JTA_TXN_COORD=false, sun.boot.library.path=C:\Program Files\Java\jdk1.8.0_241\jre\bin, sun.java.command=worker.org.gradle.process.internal.worker.GradleWorkerMain 'Gradle Test Executor 2', sun.cpu.endian=little, user.home=C:\Users\yaakov.berkovitch, user.language=en, java.specification.vendor=Oracle Corporation, org.gradle.native=false, java.home=C:\Program Files\Java\jdk1.8.0_241\jre, file.separator=\, line.separator=
, java.vm.specification.vendor=Oracle Corporation, java.specification.name=Java Platform API Specification, hibernate.transaction.coordinator_class=class org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorBuilderImpl, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, javax.persistence.jdbc.driver=org.postgresql.Driver, sun.boot.class.path=C:\Program Files\Java\jdk1.8.0_241\jre\lib\resources.jar;C:\Program Files\Java\jdk1.8.0_241\jre\lib\rt.jar;C:\Program Files\Java\jdk1.8.0_241\jre\lib\sunrsasign.jar;C:\Program Files\Java\jdk1.8.0_241\jre\lib\jsse.jar;C:\Program Files\Java\jdk1.8.0_241\jre\lib\jce.jar;C:\Program Files\Java\jdk1.8.0_241\jre\lib\charsets.jar;C:\Program Files\Java\jdk1.8.0_241\jre\lib\jfr.jar;C:\Program Files\Java\jdk1.8.0_241\jre\classes, user.script=, sun.management.compiler=HotSpot 64-Bit Tiered Compilers, java.runtime.version=1.8.0_241-b07, user.name=yaakov.berkovitch, path.separator=;, javax.persistence.schema-generation.database.motion=drop-and-create, hibernate.connection.username=****, os.version=10.0, java.endorsed.dirs=C:\Program Files\Java\jdk1.8.0_241\jre\lib\endorsed, java.runtime.name=Java(TM) SE Runtime Environment, hibernate.connection.url=jdbc:postgresql://10.20.14.3:5432/demo, file.encoding=windows-1252, hibernate.ejb.persistenceUnitName=appviz-persistence-unit, java.vm.name=Java HotSpot(TM) 64-Bit Server VM, hibernate.connection.driver_class=org.postgresql.Driver, java.vendor.url.bug=http://bugreport.sun.com/bugreport/, java.io.tmpdir=C:\Users\YAAKOV~1.BER\AppData\Local\Temp\, java.version=1.8.0_241, user.dir=C:\dev\sequence-issue, os.arch=amd64, java.vm.specification.name=Java Virtual Machine Specification, java.awt.printerjob=sun.awt.windows.WPrinterJob, hibernate.connection.password=****, sun.os.patch.level=, hibernate.boot.CfgXmlAccessService.key=org.hibernate.reactive.provider.ReactiveServiceRegistryBuilder$1@60c9f9f5, java.library.path=C:\Program Files\Java\jdk1.8.0_241\bin;C:\windows\Sun\Java\bin;C:\windows\system32;C:\windows;C:\Program Files\AdoptOpenJDK\jdk-11.0.9.11-openj9\bin;C:\Python27\;C:\Python27\Scripts;C:\Program Files (x86)\Common Files\Oracle\Java\javapath;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\windows\System32\OpenSSH\;C:\Program Files\doxygen\bin;C:\Program Files\Git\cmd;C:\Program Files\Git\mingw64\libexec\git-core;C:\Program Files\Git\usr\bin;C:\Program Files\Amazon\AWSCLIV2\;C:\ProgramData\chocolatey\bin;C:\Program Files\TortoiseGit\bin;C:\Program Files\nodejs\;C:\Program Files\PuTTY\;C:\Users\yaakov.berkovitch\AppData\Local\Microsoft\WindowsApps;C:\Users\yaakov.berkovitch\AppData\Local\atom\bin;C:\apache-maven-3.6.3-bin\apache-maven-3.6.3\bin;C:\Program Files\Java\jdk1.8.0_241\bin;C:\Users\yaakov.berkovitch\AppData\Roaming\npm;., java.vendor=Oracle Corporation, java.vm.info=mixed mode, java.vm.version=25.241-b07, hibernate.bytecode.use_reflection_optimizer=false, sun.io.unicode.encoding=UnicodeLittle, java.ext.dirs=C:\Program Files\Java\jdk1.8.0_241\jre\lib\ext;C:\windows\Sun\Java\lib\ext, javax.persistence.jdbc.password=****, java.class.version=52.0}
18:44:47.162 [Test worker] DEBUG org.hibernate.secure.spi.JaccIntegrator - Skipping JACC integration as it was not enabled
18:44:47.162 [Test worker] INFO org.hibernate.reactive.provider.impl.ReactiveIntegrator - HRX000001: Hibernate Reactive Preview
18:44:47.162 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveAutoFlushEventListener@79c4c4cd`] for match against existing listener [`org.hibernate.event.internal.DefaultAutoFlushEventListener@26f325ab`]
18:44:47.162 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveAutoFlushEventListener@79c4c4cd`] for match against existing listener [`org.hibernate.event.internal.DefaultAutoFlushEventListener@26f325ab`]
18:44:47.162 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveAutoFlushEventListener@79c4c4cd` and `org.hibernate.event.internal.DefaultAutoFlushEventListener@26f325ab`
18:44:47.162 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultAutoFlushEventListener@26f325ab` -> org.hibernate.reactive.event.impl.DefaultReactiveAutoFlushEventListener@79c4c4cd
18:44:47.162 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveFlushEventListener@7a1b052f`] for match against existing listener [`org.hibernate.event.internal.DefaultFlushEventListener@28c60b9d`]
18:44:47.162 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveFlushEventListener@7a1b052f`] for match against existing listener [`org.hibernate.event.internal.DefaultFlushEventListener@28c60b9d`]
18:44:47.162 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveFlushEventListener@7a1b052f` and `org.hibernate.event.internal.DefaultFlushEventListener@28c60b9d`
18:44:47.162 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultFlushEventListener@28c60b9d` -> org.hibernate.reactive.event.impl.DefaultReactiveFlushEventListener@7a1b052f
18:44:47.162 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveFlushEntityEventListener@4e78f5b2`] for match against existing listener [`org.hibernate.event.internal.DefaultFlushEntityEventListener@56fa9503`]
18:44:47.162 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveFlushEntityEventListener@4e78f5b2`] for match against existing listener [`org.hibernate.event.internal.DefaultFlushEntityEventListener@56fa9503`]
18:44:47.162 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveFlushEntityEventListener@4e78f5b2` and `org.hibernate.event.internal.DefaultFlushEntityEventListener@56fa9503`
18:44:47.162 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultFlushEntityEventListener@56fa9503` -> org.hibernate.reactive.event.impl.DefaultReactiveFlushEntityEventListener@4e78f5b2
18:44:47.179 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactivePersistEventListener@66a046d5`] for match against existing listener [`org.hibernate.event.internal.DefaultPersistEventListener@5e7110ad`]
18:44:47.179 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactivePersistEventListener@66a046d5`] for match against existing listener [`org.hibernate.event.internal.DefaultPersistEventListener@5e7110ad`]
18:44:47.179 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactivePersistEventListener@66a046d5` and `org.hibernate.event.internal.DefaultPersistEventListener@5e7110ad`
18:44:47.179 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultPersistEventListener@5e7110ad` -> org.hibernate.reactive.event.impl.DefaultReactivePersistEventListener@66a046d5
18:44:47.179 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactivePersistOnFlushEventListener@6742c9a6`] for match against existing listener [`org.hibernate.event.internal.DefaultPersistOnFlushEventListener@3160499b`]
18:44:47.179 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactivePersistOnFlushEventListener@6742c9a6`] for match against existing listener [`org.hibernate.event.internal.DefaultPersistOnFlushEventListener@3160499b`]
18:44:47.179 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactivePersistOnFlushEventListener@6742c9a6` and `org.hibernate.event.internal.DefaultPersistOnFlushEventListener@3160499b`
18:44:47.179 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultPersistOnFlushEventListener@3160499b` -> org.hibernate.reactive.event.impl.DefaultReactivePersistOnFlushEventListener@6742c9a6
18:44:47.179 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveMergeEventListener@7bdee9d2`] for match against existing listener [`org.hibernate.event.internal.DefaultMergeEventListener@789a2895`]
18:44:47.179 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveMergeEventListener@7bdee9d2`] for match against existing listener [`org.hibernate.event.internal.DefaultMergeEventListener@789a2895`]
18:44:47.179 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveMergeEventListener@7bdee9d2` and `org.hibernate.event.internal.DefaultMergeEventListener@789a2895`
18:44:47.179 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultMergeEventListener@789a2895` -> org.hibernate.reactive.event.impl.DefaultReactiveMergeEventListener@7bdee9d2
18:44:47.179 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveDeleteEventListener@5a1b1314`] for match against existing listener [`org.hibernate.event.internal.DefaultDeleteEventListener@6c81ed95`]
18:44:47.179 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveDeleteEventListener@5a1b1314`] for match against existing listener [`org.hibernate.event.internal.DefaultDeleteEventListener@6c81ed95`]
18:44:47.179 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveDeleteEventListener@5a1b1314` and `org.hibernate.event.internal.DefaultDeleteEventListener@6c81ed95`
18:44:47.179 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultDeleteEventListener@6c81ed95` -> org.hibernate.reactive.event.impl.DefaultReactiveDeleteEventListener@5a1b1314
18:44:47.195 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveRefreshEventListener@14143a7d`] for match against existing listener [`org.hibernate.event.internal.DefaultRefreshEventListener@13ad74b1`]
18:44:47.195 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveRefreshEventListener@14143a7d`] for match against existing listener [`org.hibernate.event.internal.DefaultRefreshEventListener@13ad74b1`]
18:44:47.195 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveRefreshEventListener@14143a7d` and `org.hibernate.event.internal.DefaultRefreshEventListener@13ad74b1`
18:44:47.195 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultRefreshEventListener@13ad74b1` -> org.hibernate.reactive.event.impl.DefaultReactiveRefreshEventListener@14143a7d
18:44:47.199 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveLockEventListener@55584535`] for match against existing listener [`org.hibernate.event.internal.DefaultLockEventListener@78bd4f58`]
18:44:47.199 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveLockEventListener@55584535`] for match against existing listener [`org.hibernate.event.internal.DefaultLockEventListener@78bd4f58`]
18:44:47.199 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveLockEventListener@55584535` and `org.hibernate.event.internal.DefaultLockEventListener@78bd4f58`
18:44:47.199 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultLockEventListener@78bd4f58` -> org.hibernate.reactive.event.impl.DefaultReactiveLockEventListener@55584535
18:44:47.199 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveLoadEventListener@ad66688`] for match against existing listener [`org.hibernate.event.internal.DefaultLoadEventListener@8292200`]
18:44:47.199 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveLoadEventListener@ad66688`] for match against existing listener [`org.hibernate.event.internal.DefaultLoadEventListener@8292200`]
18:44:47.199 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveLoadEventListener@ad66688` and `org.hibernate.event.internal.DefaultLoadEventListener@8292200`
18:44:47.199 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultLoadEventListener@8292200` -> org.hibernate.reactive.event.impl.DefaultReactiveLoadEventListener@ad66688
18:44:47.199 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveInitializeCollectionEventListener@7a65f0ac`] for match against existing listener [`org.hibernate.event.internal.DefaultInitializeCollectionEventListener@3b9de4c2`]
18:44:47.199 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveInitializeCollectionEventListener@7a65f0ac`] for match against existing listener [`org.hibernate.event.internal.DefaultInitializeCollectionEventListener@3b9de4c2`]
18:44:47.199 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveInitializeCollectionEventListener@7a65f0ac` and `org.hibernate.event.internal.DefaultInitializeCollectionEventListener@3b9de4c2`
18:44:47.199 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultInitializeCollectionEventListener@3b9de4c2` -> org.hibernate.reactive.event.impl.DefaultReactiveInitializeCollectionEventListener@7a65f0ac
18:44:47.199 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactivePostLoadEventListener@7e40ae38`] for match against existing listener [`org.hibernate.event.internal.DefaultPostLoadEventListener@2aafeec6`]
18:44:47.199 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactivePostLoadEventListener@7e40ae38`] for match against existing listener [`org.hibernate.event.internal.DefaultPostLoadEventListener@2aafeec6`]
18:44:47.199 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactivePostLoadEventListener@7e40ae38` and `org.hibernate.event.internal.DefaultPostLoadEventListener@2aafeec6`
18:44:47.199 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultPostLoadEventListener@2aafeec6` -> org.hibernate.reactive.event.impl.DefaultReactivePostLoadEventListener@7e40ae38
18:44:47.212 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveResolveNaturalIdEventListener@22682956`] for match against existing listener [`org.hibernate.event.internal.DefaultResolveNaturalIdEventListener@14a3ecc2`]
18:44:47.212 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Checking incoming listener [`org.hibernate.reactive.event.impl.DefaultReactiveResolveNaturalIdEventListener@22682956`] for match against existing listener [`org.hibernate.event.internal.DefaultResolveNaturalIdEventListener@14a3ecc2`]
18:44:47.212 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Found listener match between `org.hibernate.reactive.event.impl.DefaultReactiveResolveNaturalIdEventListener@22682956` and `org.hibernate.event.internal.DefaultResolveNaturalIdEventListener@14a3ecc2`
18:44:47.212 [Test worker] DEBUG org.hibernate.event.service.internal.EventListenerGroupImpl - Replacing listener registration (REPLACE_ORIGINAL) : `org.hibernate.event.internal.DefaultResolveNaturalIdEventListener@14a3ecc2` -> org.hibernate.reactive.event.impl.DefaultReactiveResolveNaturalIdEventListener@22682956
18:44:47.212 [Test worker] DEBUG org.hibernate.internal.SessionFactoryImpl - Instantiated session factory
18:44:47.212 [Test worker] DEBUG org.hibernate.type.spi.TypeConfiguration$Scope - Scoping TypeConfiguration [org.hibernate.type.spi.TypeConfiguration@380bb041] to SessionFactoryImpl [org.hibernate.reactive.session.impl.ReactiveSessionFactoryImpl@12d7c7ff]
18:44:47.312 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - Not known whether passed class name [model.Employee] is safe
18:44:47.312 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - No temp ClassLoader provided; using live ClassLoader for loading potentially unsafe class : model.Employee
18:44:47.628 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - Not known whether passed class name [model.Project] is safe
18:44:47.628 [Test worker] DEBUG org.hibernate.boot.internal.ClassLoaderAccessImpl - No temp ClassLoader provided; using live ClassLoader for loading potentially unsafe class : model.Project
18:44:47.674 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Static SQL for collection: model.Employee.projects
18:44:47.674 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  Row insert: insert into Employee_Project (employee_id, project_id) values ($1, $2)
18:44:47.674 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  Row update: update Employee_Project set project_id=$1 where employee_id=$2 and project_id=$3
18:44:47.674 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  Row delete: delete from Employee_Project where employee_id=$1 and project_id=$2
18:44:47.674 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  One-shot delete: delete from Employee_Project where employee_id=$1
18:44:47.674 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Static SQL for collection: model.Project.employees
18:44:47.675 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  Row insert: insert into Employee_Project (project_id, employee_id) values ($1, $2)
18:44:47.675 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  Row update: update Employee_Project set employee_id=$1 where project_id=$2 and employee_id=$3
18:44:47.675 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  Row delete: delete from Employee_Project where project_id=$1 and employee_id=$2
18:44:47.675 [Test worker] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  One-shot delete: delete from Employee_Project where project_id=$1
18:44:47.679 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Static SQL for entity: model.Employee
18:44:47.679 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Version select: select employee_id from Employee where employee_id =$1
18:44:47.679 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Snapshot select: select employee_.employee_id, employee_.first_name as first_na2_0_, employee_.last_name as last_nam3_0_ from Employee employee_ where employee_.employee_id=$1
18:44:47.679 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Insert 0: insert into Employee (first_name, last_name, employee_id) values ($1, $2, $3)
18:44:47.679 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Update 0: update Employee set first_name=$1, last_name=$2 where employee_id=$3
18:44:47.679 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Delete 0: delete from Employee where employee_id=$1
18:44:47.728 [Test worker] DEBUG org.hibernate.loader.plan.build.internal.spaces.QuerySpacesImpl - Adding QuerySpace : uid = <gen:0> -> org.hibernate.loader.plan.build.internal.spaces.EntityQuerySpaceImpl@39a31842]
18:44:47.728 [Test worker] DEBUG org.hibernate.persister.walking.spi.MetamodelGraphWalker - Visiting attribute path : firstName
18:44:47.728 [Test worker] DEBUG org.hibernate.persister.walking.spi.MetamodelGraphWalker - Visiting attribute path : lastName
18:44:47.728 [Test worker] DEBUG org.hibernate.persister.walking.spi.MetamodelGraphWalker - Visiting attribute path : projects
18:44:47.728 [Test worker] DEBUG org.hibernate.loader.plan.build.internal.spaces.QuerySpacesImpl - Adding QuerySpace : uid = <gen:1> -> org.hibernate.loader.plan.build.internal.spaces.CollectionQuerySpaceImpl@75bcf066]
18:44:47.728 [Test worker] DEBUG org.hibernate.loader.plan.build.internal.spaces.QuerySpacesImpl - Adding QuerySpace : uid = <gen:2> -> org.hibernate.loader.plan.build.internal.spaces.EntityQuerySpaceImpl@226afa00]
18:44:47.728 [Test worker] DEBUG org.hibernate.loader.plan.build.internal.FetchStyleLoadPlanBuildingAssociationVisitationStrategy - Building LoadPlan...
18:44:47.762 [Test worker] DEBUG org.hibernate.loader.plan.exec.internal.LoadQueryJoinAndFetchProcessor - processing queryspace <gen:0>
18:44:47.762 [Test worker] DEBUG org.hibernate.loader.plan.build.spi.LoadPlanTreePrinter - LoadPlan(entity=model.Employee)
    - Returns
       - EntityReturnImpl(entity=model.Employee, querySpaceUid=<gen:0>, path=model.Employee)
          - CollectionAttributeFetchImpl(collection=model.Employee.projects, querySpaceUid=<gen:1>, path=model.Employee.projects)
             - (collection element) CollectionFetchableElementEntityGraph(entity=model.Project, querySpaceUid=<gen:2>, path=model.Employee.projects.<elements>)
    - QuerySpaces
       - EntityQuerySpaceImpl(uid=<gen:0>, entity=model.Employee)
          - SQL table alias mapping - employee0_
          - alias suffix - 0_
          - suffixed key columns - {employee1_0_0_}

18:44:47.762 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Static SQL for entity: model.Project
18:44:47.762 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Version select: select project_id from Project where project_id =$1
18:44:47.762 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Snapshot select: select project_.project_id, project_.title as title2_2_ from Project project_ where project_.project_id=$1
18:44:47.762 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Insert 0: insert into Project (title, project_id) values ($1, $2)
18:44:47.762 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Update 0: update Project set title=$1 where project_id=$2
18:44:47.762 [Test worker] DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Delete 0: delete from Project where project_id=$1
18:44:47.762 [Test worker] DEBUG org.hibernate.loader.plan.build.internal.spaces.QuerySpacesImpl - Adding QuerySpace : uid = <gen:0> -> org.hibernate.loader.plan.build.internal.spaces.EntityQuerySpaceImpl@70076bb7]
18:44:47.762 [Test worker] DEBUG org.hibernate.persister.walking.spi.MetamodelGraphWalker - Visiting attribute path : employees
18:44:47.762 [Test worker] DEBUG org.hibernate.loader.plan.build.internal.spaces.QuerySpacesImpl - Adding QuerySpace : uid = <gen:1> -> org.hibernate.loader.plan.build.internal.spaces.CollectionQuerySpaceImpl@6ab1af37]
18:44:47.762 [Test worker] DEBUG org.hibernate.loader.plan.build.internal.spaces.QuerySpacesImpl - Adding QuerySpace : uid = <gen:2> -> org.hibernate.loader.plan.build.internal.spaces.EntityQuerySpaceImpl@107de4dd]
18:44:47.762 [Test worker] DEBUG org.hibernate.persister.walking.spi.MetamodelGraphWalker - Visiting attribute path : title
18:44:47.762 [Test worker] DEBUG org.hibernate.loader.plan.build.internal.FetchStyleLoadPlanBuildingAssociationVisitationStrategy - Building LoadPlan...
18:44:47.762 [Test worker] DEBUG org.hibernate.loader.plan.exec.internal.LoadQueryJoinAndFetchProcessor - processing queryspace <gen:0>
18:44:47.762 [Test worker] DEBUG org.hibernate.loader.plan.build.spi.LoadPlanTreePrinter - LoadPlan(entity=model.Project)
    - Returns
       - EntityReturnImpl(entity=model.Project, querySpaceUid=<gen:0>, path=model.Project)
          - CollectionAttributeFetchImpl(collection=model.Project.employees, querySpaceUid=<gen:1>, path=model.Project.employees)
             - (collection element) CollectionFetchableElementEntityGraph(entity=model.Employee, querySpaceUid=<gen:2>, path=model.Project.employees.<elements>)
    - QuerySpaces
       - EntityQuerySpaceImpl(uid=<gen:0>, entity=model.Project)
          - SQL table alias mapping - project0_
          - alias suffix - 0_
          - suffixed key columns - {project_1_2_0_}

18:44:47.794 [Test worker] DEBUG org.hibernate.loader.Loader - Static select for one-to-many model.Employee.projects: select projects0_.employee_id as employee1_1_1_, projects0_.project_id as project_2_1_1_, project1_.project_id as project_1_2_0_, project1_.title as title2_2_0_ from Employee_Project projects0_ inner join Project project1_ on projects0_.project_id=project1_.project_id where projects0_.employee_id=$1
18:44:47.795 [Test worker] DEBUG org.hibernate.loader.Loader - Static select for one-to-many model.Project.employees: select employees0_.project_id as project_2_1_1_, employees0_.employee_id as employee1_1_1_, employee1_.employee_id as employee1_0_0_, employee1_.first_name as first_na2_0_0_, employee1_.last_name as last_nam3_0_0_ from Employee_Project employees0_ inner join Employee employee1_ on employees0_.employee_id=employee1_.employee_id where employees0_.project_id=$1
18:44:47.829 [Test worker] DEBUG org.hibernate.tool.schema.spi.SchemaManagementToolCoordinator - No actions specified; doing nothing
18:44:47.829 [Test worker] DEBUG org.hibernate.query.spi.NamedQueryRepository - Checking 0 named HQL queries
18:44:47.829 [Test worker] DEBUG org.hibernate.query.spi.NamedQueryRepository - Checking 0 named SQL queries
18:44:47.845 [Test worker] DEBUG org.hibernate.internal.SessionFactoryRegistry - Initializing SessionFactoryRegistry : org.hibernate.internal.SessionFactoryRegistry@63daf328
18:44:47.845 [Test worker] DEBUG org.hibernate.internal.SessionFactoryRegistry - Registering SessionFactory: 6e3abcf5-0a8d-4676-8ea9-9c9eb7ba3321 (<unnamed>)
18:44:47.845 [Test worker] DEBUG org.hibernate.internal.SessionFactoryRegistry - Not binding SessionFactory to JNDI, no JNDI name configured
18:44:47.879 [Test worker] DEBUG io.vertx.core.logging.LoggerFactory - Using io.vertx.core.logging.SLF4JLogDelegateFactory
18:44:47.912 [Test worker] DEBUG io.netty.util.internal.logging.InternalLoggerFactory - Using SLF4J as the default logging framework
18:44:47.912 [Test worker] DEBUG io.netty.util.internal.PlatformDependent - Platform: Windows
18:44:47.912 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - -Dio.netty.noUnsafe: false
18:44:47.912 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - Java version: 8
18:44:47.912 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - sun.misc.Unsafe.theUnsafe: available
18:44:47.912 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - sun.misc.Unsafe.copyMemory: available
18:44:47.912 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - java.nio.Buffer.address: available
18:44:47.912 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - direct buffer constructor: available
18:44:47.912 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - java.nio.Bits.unaligned: available, true
18:44:47.912 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - jdk.internal.misc.Unsafe.allocateUninitializedArray(int): unavailable prior to Java9
18:44:47.912 [Test worker] DEBUG io.netty.util.internal.PlatformDependent0 - java.nio.DirectByteBuffer.<init>(long, int): available
18:44:47.912 [Test worker] DEBUG io.netty.util.internal.PlatformDependent - sun.misc.Unsafe: available
18:44:47.912 [Test worker] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.tmpdir: C:\Users\YAAKOV~1.BER\AppData\Local\Temp (java.io.tmpdir)
18:44:47.912 [Test worker] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.bitMode: 64 (sun.arch.data.model)
18:44:47.912 [Test worker] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.maxDirectMemory: 510656512 bytes
18:44:47.912 [Test worker] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.uninitializedArrayAllocationThreshold: -1
18:44:47.928 [Test worker] DEBUG io.netty.util.internal.CleanerJava6 - java.nio.ByteBuffer.cleaner(): available
18:44:47.928 [Test worker] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.noPreferDirect: false
18:44:47.945 [Test worker] DEBUG io.netty.util.ResourceLeakDetector - -Dio.netty.leakDetection.level: simple
18:44:47.945 [Test worker] DEBUG io.netty.util.ResourceLeakDetector - -Dio.netty.leakDetection.targetRecords: 4
18:44:47.978 [Test worker] DEBUG io.netty.channel.MultithreadEventLoopGroup - -Dio.netty.eventLoopThreads: 24
18:44:48.000 [Test worker] DEBUG io.netty.util.internal.InternalThreadLocalMap - -Dio.netty.threadLocalMap.stringBuilder.initialSize: 1024
18:44:48.000 [Test worker] DEBUG io.netty.util.internal.InternalThreadLocalMap - -Dio.netty.threadLocalMap.stringBuilder.maxSize: 4096
18:44:48.012 [Test worker] DEBUG io.netty.channel.nio.NioEventLoop - -Dio.netty.noKeySetOptimization: false
18:44:48.012 [Test worker] DEBUG io.netty.channel.nio.NioEventLoop - -Dio.netty.selectorAutoRebuildThreshold: 512
18:44:48.012 [Test worker] DEBUG io.netty.util.internal.PlatformDependent - org.jctools-core.MpscChunkedArrayQueue: available
18:44:48.062 [Test worker] DEBUG io.netty.resolver.dns.DefaultDnsServerAddressStreamProvider - Default DNS servers: [/192.168.11.20:53, /192.168.11.21:53, /10.254.254.1:53, /8.8.8.8:53, /208.91.112.53:53, /208.91.112.52:53] (sun.net.dns.ResolverConfiguration)
18:44:48.062 [Test worker] DEBUG io.netty.util.NetUtil - -Djava.net.preferIPv4Stack: false
18:44:48.062 [Test worker] DEBUG io.netty.util.NetUtil - -Djava.net.preferIPv6Addresses: false
18:44:48.462 [Test worker] DEBUG io.netty.util.NetUtilInitializations - Loopback interface: lo (Software Loopback Interface 1, 127.0.0.1)
18:44:48.462 [Test worker] DEBUG io.netty.util.NetUtil - Failed to get SOMAXCONN from sysctl and file \proc\sys\net\core\somaxconn. Default: 200
18:44:48.500 [Test worker] INFO org.hibernate.reactive.pool.impl.DefaultSqlClientPool - HRX000011: SQL Client URL [jdbc:postgresql://10.20.14.3:5432/demo]
18:44:48.500 [Test worker] INFO org.hibernate.reactive.pool.impl.DefaultSqlClientPool - HRX000012: Connection pool size: 5
18:44:48.612 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.numHeapArenas: 4
18:44:48.612 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.numDirectArenas: 5
18:44:48.612 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.pageSize: 8192
18:44:48.612 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.maxOrder: 11
18:44:48.612 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.chunkSize: 16777216
18:44:48.612 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.smallCacheSize: 256
18:44:48.612 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.normalCacheSize: 64
18:44:48.612 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.maxCachedBufferCapacity: 32768
18:44:48.612 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.cacheTrimInterval: 8192
18:44:48.612 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.cacheTrimIntervalMillis: 0
18:44:48.612 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.useCacheForAllThreads: true
18:44:48.612 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.PooledByteBufAllocator - -Dio.netty.allocator.maxCachedByteBuffersPerChunk: 1023
18:44:48.645 [vert.x-eventloop-thread-0] DEBUG io.netty.channel.DefaultChannelId - -Dio.netty.processId: 8096 (auto-detected)
18:44:49.028 [vert.x-eventloop-thread-0] DEBUG io.netty.channel.DefaultChannelId - -Dio.netty.machineId: 80:6d:97:ff:fe:00:be:eb (auto-detected)
18:44:49.045 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.ByteBufUtil - -Dio.netty.allocator.type: pooled
18:44:49.045 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.ByteBufUtil - -Dio.netty.threadLocalDirectBufferSize: 0
18:44:49.045 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.ByteBufUtil - -Dio.netty.maxThreadLocalCharBufferSize: 16384
18:44:49.527 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.AbstractByteBuf - -Dio.netty.buffer.checkAccessible: true
18:44:49.527 [vert.x-eventloop-thread-0] DEBUG io.netty.buffer.AbstractByteBuf - -Dio.netty.buffer.checkBounds: true
18:44:49.528 [vert.x-eventloop-thread-0] DEBUG io.netty.util.ResourceLeakDetectorFactory - Loaded default ResourceLeakDetector: io.netty.util.ResourceLeakDetector@76f82cf9
18:44:49.528 [vert.x-eventloop-thread-0] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.maxCapacityPerThread: 4096
18:44:49.528 [vert.x-eventloop-thread-0] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.maxSharedCapacityFactor: 2
18:44:49.528 [vert.x-eventloop-thread-0] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.linkCapacity: 16
18:44:49.528 [vert.x-eventloop-thread-0] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.ratio: 8
18:44:49.528 [vert.x-eventloop-thread-0] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.delayedQueue.ratio: 8
18:44:49.629 [vert.x-eventloop-thread-0] DEBUG org.hibernate.stat.internal.StatisticsInitiator - Statistics initialized [enabled=false]
18:44:49.662 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - select nextval ('hibernate_sequence')
18:44:49.745 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - select nextval ('hibernate_sequence')
18:44:49.761 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - select nextval ('hibernate_sequence')
18:44:49.762 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - select nextval ('hibernate_sequence')
18:44:49.762 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - select nextval ('hibernate_sequence')
18:44:49.762 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - select nextval ('hibernate_sequence')
18:44:49.762 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - select nextval ('hibernate_sequence')
18:44:49.762 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - select nextval ('hibernate_sequence')
18:44:49.762 [vert.x-eventloop-thread-0] DEBUG org.hibernate.reactive.event.impl.AbstractReactiveFlushingEventListener - Processing flush-time cascades
18:44:49.778 [vert.x-eventloop-thread-0] DEBUG org.hibernate.reactive.event.impl.AbstractReactiveFlushingEventListener - Dirty checking collections
18:44:49.778 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.internal.Collections - Collection found: [model.Employee.projects#74], was: [<unreferenced>] (initialized)
18:44:49.778 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.internal.Collections - Collection found: [model.Project.employees#75], was: [<unreferenced>] (initialized)
18:44:49.778 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.internal.Collections - Collection found: [model.Project.employees#76], was: [<unreferenced>] (initialized)
18:44:49.778 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.internal.Collections - Collection found: [model.Employee.projects#77], was: [<unreferenced>] (initialized)
18:44:49.778 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.internal.Collections - Collection found: [model.Employee.projects#78], was: [<unreferenced>] (initialized)
18:44:49.778 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.internal.Collections - Collection found: [model.Employee.projects#79], was: [<unreferenced>] (initialized)
18:44:49.778 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.internal.Collections - Collection found: [model.Employee.projects#80], was: [<unreferenced>] (initialized)
18:44:49.778 [vert.x-eventloop-thread-0] DEBUG org.hibernate.engine.internal.Collections - Collection found: [model.Employee.projects#81], was: [<unreferenced>] (initialized)
18:44:49.795 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee (first_name, last_name, employee_id) values ($1, $2, $3)
18:44:49.801 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Project (title, project_id) values ($1, $2)
18:44:49.801 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Project (title, project_id) values ($1, $2)
18:44:49.812 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee (first_name, last_name, employee_id) values ($1, $2, $3)
18:44:49.812 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee (first_name, last_name, employee_id) values ($1, $2, $3)
18:44:49.812 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee (first_name, last_name, employee_id) values ($1, $2, $3)
18:44:49.812 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee (first_name, last_name, employee_id) values ($1, $2, $3)
18:44:49.812 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee (first_name, last_name, employee_id) values ($1, $2, $3)
18:44:49.812 [vert.x-eventloop-thread-0] DEBUG org.hibernate.reactive.persister.collection.impl.ReactiveBasicCollectionPersister - Inserting collection: [model.Employee.projects#74]
18:44:49.827 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee_Project (employee_id, project_id) values ($1, $2)
18:44:49.828 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee_Project (employee_id, project_id) values ($1, $2)
18:44:49.828 [vert.x-eventloop-thread-0] DEBUG org.hibernate.reactive.persister.collection.impl.ReactiveBasicCollectionPersister - Inserting collection: [model.Employee.projects#77]
18:44:49.828 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee_Project (employee_id, project_id) values ($1, $2)
18:44:49.828 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee_Project (employee_id, project_id) values ($1, $2)
18:44:49.828 [vert.x-eventloop-thread-0] DEBUG org.hibernate.reactive.persister.collection.impl.ReactiveBasicCollectionPersister - Inserting collection: [model.Employee.projects#78]
18:44:49.828 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee_Project (employee_id, project_id) values ($1, $2)
18:44:49.828 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee_Project (employee_id, project_id) values ($1, $2)
18:44:49.844 [vert.x-eventloop-thread-0] DEBUG org.hibernate.reactive.persister.collection.impl.ReactiveBasicCollectionPersister - Inserting collection: [model.Employee.projects#79]
18:44:49.845 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee_Project (employee_id, project_id) values ($1, $2)
18:44:49.845 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee_Project (employee_id, project_id) values ($1, $2)
18:44:49.845 [vert.x-eventloop-thread-0] DEBUG org.hibernate.reactive.persister.collection.impl.ReactiveBasicCollectionPersister - Inserting collection: [model.Employee.projects#80]
18:44:49.845 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee_Project (employee_id, project_id) values ($1, $2)
18:44:49.845 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee_Project (employee_id, project_id) values ($1, $2)
18:44:49.845 [vert.x-eventloop-thread-0] DEBUG org.hibernate.reactive.persister.collection.impl.ReactiveBasicCollectionPersister - Inserting collection: [model.Employee.projects#81]
18:44:49.845 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee_Project (employee_id, project_id) values ($1, $2)
18:44:49.845 [vert.x-eventloop-thread-0] DEBUG org.hibernate.SQL - insert into Employee_Project (employee_id, project_id) values ($1, $2)
employee name = Oven, name = Oven
employee name = Norman, name = Norman
employee name = Dylan, name = Dylan
employee name = Borg, name = Borg
employee name = Regan, name = Regan
employee name = Kenedy, name = Kenedy
BUILD SUCCESSFUL in 7s
5 actionable tasks: 2 executed, 3 up-to-date
6:44:50 PM: Task execution finished ':test --tests "ManyToManyIntegrationTest"'.

[gavinking]

Wait, could this whole problem just be because you're creating your own sequence (instead of letting Hibernate generate the DDL) with a mismatched increment?

For the mapping you've given us, the sequence should be created with:

create sequence objects_id_seq start 1 increment 50

Or, if not, you should change the mapping to specify @SequenceGenerator(name = "objects_id_seq", sequenceName = "objects_id_seq", allocationSize = 1).

[gavinking]

OK, when I created the sequence with:

create sequence objects_id_seq start 1 increment 50;

The test runs (does not hang) and completes successfully.

I strongly suspect that this was the issue.

[gavinking]

Wait, WTH, I might be misunderstanding. @yaakov-berkovitch does your test not create its own database schema?!

[gavinking]

Look, there were a couple of big problems with your test code:

1. You were catching and swallowing errors and returning null, instead of letting errors propagate out so we could see them.
2. Your test code wasn't exporting its database (it never ran init-schema.sql).
3. The init-schema.sql file didn't create the database sequence.

Now, after fixing those issues, the test actually runs perfectly fine, and doesn't demonstrate the issue.

I was, however, able to reproduce the failure by:

1. setting allocationSize = 3 in your @SequenceGenerator, and
2. not setting increment in the DDL statement that creates the sequence.

The failure is resolved by either:

1. setting allocationSize = 1 in your @SequenceGenerator, or
2. setting increment 3 in the DDL statement that creates the sequence.

I therefore conclude that this is almost certainly the cause of your issue.

[yaakov-berkovitch]

Again very appreciate your help and time you burnt, and sorry for the confusion I caused. After applying your advise about how to fix the failure, it works also for me. Thanks.