2025-02-03 14:55:51,994 DEBUG [org.hibernate.orm.results] (executor-thread-16) Extracted JDBC value [0] - [87f8150c-450a-4e79-9eb5-b671f63e4612]
2025-02-03 14:55:51,994 DEBUG [org.hibernate.orm.results.loading.org.hibernate.orm.results.loading.collection] (executor-thread-16) (DelayedCollectionInitializer) Current row collection key : org.keycloak.models.jpa.entities.UserEntity(u).credentials#87f8150c-450a-4e79-9eb5-b671f63e4612
2025-02-03 14:55:51,994 DEBUG [org.hibernate.orm.results] (executor-thread-16) Extracted JDBC value [0] - [87f8150c-450a-4e79-9eb5-b671f63e4612]
2025-02-03 14:55:51,994 DEBUG [org.hibernate.orm.results.loading.org.hibernate.orm.results.loading.collection] (executor-thread-16) (DelayedCollectionInitializer) Current row collection key : org.keycloak.models.jpa.entities.UserEntity(u).federatedIdentities#87f8150c-450a-4e79-9eb5-b671f63e4612
2025-02-03 14:55:51,994 DEBUG [org.hibernate.orm.results] (executor-thread-16) Extracted JDBC value [0] - [87f8150c-450a-4e79-9eb5-b671f63e4612]
2025-02-03 14:55:51,994 DEBUG [org.hibernate.orm.results.loading.org.hibernate.orm.results.loading.collection] (executor-thread-16) (DelayedCollectionInitializer) Current row collection key : org.keycloak.models.jpa.entities.UserEntity(u).requiredActions#87f8150c-450a-4e79-9eb5-b671f63e4612
2025-02-03 14:55:51,994 DEBUG [org.hibernate.orm.results.loading.entity] (executor-thread-16) (EntityResultInitializer) Created new entity instance [org.keycloak.models.jpa.entities.UserEntity(u)#87f8150c-450a-4e79-9eb5-b671f63e4612] : 1168066105
2025-02-03 14:55:51,994 DEBUG [org.hibernate.orm.results] (executor-thread-16) Extracted JDBC value [1] - [1730475612049]
2025-02-03 14:55:51,994 DEBUG [org.hibernate.orm.results] (executor-thread-16) Extracted JDBC value [2] - [olga_kishina@mail.ru]
2025-02-03 14:55:51,995 DEBUG [org.hibernate.orm.results] (executor-thread-16) Extracted JDBC value [3] - [olga_kishina@mail.ru]
2025-02-03 14:55:51,995 DEBUG [org.hibernate.orm.results] (executor-thread-16) Extracted JDBC value [4] - [false]
2025-02-03 14:55:51,995 DEBUG [org.hibernate.orm.results] (executor-thread-16) Extracted JDBC value [5] - [true]
2025-02-03 14:55:51,995 DEBUG [org.hibernate.orm.results] (executor-thread-16) Extracted JDBC value [6] - [null]
2025-02-03 14:55:51,995 DEBUG [org.hibernate.orm.results] (executor-thread-16) Extracted JDBC value [7] - [null]
2025-02-03 14:55:51,995 DEBUG [org.hibernate.orm.results] (executor-thread-16) Extracted JDBC value [8] - [null]
2025-02-03 14:55:51,995 DEBUG [org.hibernate.orm.results] (executor-thread-16) Extracted JDBC value [9] - [0]
2025-02-03 14:55:51,995 DEBUG [org.hibernate.orm.results] (executor-thread-16) Extracted JDBC value [10] - [7e279615-ca6b-486f-a6c8-34ded11b1a65]
2025-02-03 14:55:51,995 DEBUG [org.hibernate.orm.results] (executor-thread-16) Extracted JDBC value [11] - [null]
2025-02-03 14:55:51,995 DEBUG [org.hibernate.orm.results] (executor-thread-16) Extracted JDBC value [12] - [olga_kishina@mail.ru]
2025-02-03 14:55:51,995 DEBUG [org.hibernate.orm.results.loading.entity] (executor-thread-16) (EntityResultInitializer) Done materializing entityInstance : org.keycloak.models.jpa.entities.UserEntity(u)#87f8150c-450a-4e79-9eb5-b671f63e4612
2025-02-03 14:55:51,995 DEBUG [org.hibernate.orm.results.loading] (executor-thread-16) Calling top-level assembler (0 / 1) : org.hibernate.sql.results.graph.entity.internal.EntityAssembler@5d4235ef
2025-02-03 14:55:51,995 DEBUG [WebApplicationException] (executor-thread-16) Restarting handler chain for exception exception: org.keycloak.services.ErrorResponseException: HTTP 500 Internal Server Error
	at org.keycloak.services.ErrorResponse.error(ErrorResponse.java:43)
	at org.keycloak.services.ErrorResponse.error(ErrorResponse.java:36)
	at org.keycloak.services.ErrorResponse.exists(ErrorResponse.java:32)
	at org.keycloak.services.resources.admin.UserResource.validateUserProfile(UserResource.java:249)
	at org.keycloak.services.resources.admin.UsersResource.createUser(UsersResource.java:141)
	at org.keycloak.services.resources.admin.UsersResource$quarkusrestinvoker$createUser_49ad02a153eab6ba1571548b97a4fecbdc7a7465.invoke(Unknown Source)
	at org.jboss.resteasy.reactive.server.handlers.InvocationHandler.handle(InvocationHandler.java:29)
	at io.quarkus.resteasy.reactive.server.runtime.QuarkusResteasyReactiveRequestContext.invokeHandler(QuarkusResteasyReactiveRequestContext.java:141)
	at org.jboss.resteasy.reactive.common.core.AbstractResteasyReactiveContext.run(AbstractResteasyReactiveContext.java:147)
	at io.quarkus.vertx.core.runtime.VertxCoreRecorder$14.runWith(VertxCoreRecorder.java:582)
	at org.jboss.threads.EnhancedQueueExecutor$Task.run(EnhancedQueueExecutor.java:2513)
	at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.run(EnhancedQueueExecutor.java:1538)
	at org.jboss.threads.DelegatingRunnable.run(DelegatingRunnable.java:29)
	at org.jboss.threads.ThreadLocalResettingRunnable.run(ThreadLocalResettingRunnable.java:29)
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
	at java.base/java.lang.Thread.run(Thread.java:840)

2025-02-03 14:55:51,995 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (executor-thread-16) JtaTransactionWrapper rollback
2025-02-03 14:55:51,995 FINE  [org.postgresql.jdbc.PgConnection] (executor-thread-16)   setAutoCommit = true
2025-02-03 14:55:51,995 DEBUG [org.hibernate.resource.jdbc.internal.LogicalConnectionManagedImpl] (executor-thread-16) Initiating JDBC connection release from afterTransaction
2025-02-03 14:55:51,995 DEBUG [org.hibernate.engine.transaction.internal.TransactionImpl] (executor-thread-16) On TransactionImpl creation, JpaCompliance#isJpaTransactionComplianceEnabled == false
2025-02-03 14:55:51,995 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (executor-thread-16) JtaTransactionWrapper end
2025-02-03 14:55:52,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) new JtaTransactionWrapper
2025-02-03 14:55:52,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) was existing? false
2025-02-03 14:55:52,014 DEBUG [org.keycloak.services.scheduled.ScheduledTaskRunner] (Timer-0) Executed scheduled task PropagateLastSessionRefreshTask
2025-02-03 14:55:52,015 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper  commit
2025-02-03 14:55:52,015 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper end
2025-02-03 14:55:57,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) new JtaTransactionWrapper
2025-02-03 14:55:57,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) was existing? false
2025-02-03 14:55:57,014 DEBUG [org.keycloak.services.scheduled.ScheduledTaskRunner] (Timer-0) Executed scheduled task PropagateLastSessionRefreshTask
2025-02-03 14:55:57,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper  commit
2025-02-03 14:55:57,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper end
2025-02-03 14:56:02,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) new JtaTransactionWrapper
2025-02-03 14:56:02,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) was existing? false
2025-02-03 14:56:02,014 DEBUG [org.keycloak.services.scheduled.ScheduledTaskRunner] (Timer-0) Executed scheduled task PropagateLastSessionRefreshTask
2025-02-03 14:56:02,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper  commit
2025-02-03 14:56:02,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper end
2025-02-03 14:56:07,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) new JtaTransactionWrapper
2025-02-03 14:56:07,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) was existing? false
2025-02-03 14:56:07,014 DEBUG [org.keycloak.services.scheduled.ScheduledTaskRunner] (Timer-0) Executed scheduled task PropagateLastSessionRefreshTask
2025-02-03 14:56:07,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper  commit
2025-02-03 14:56:07,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper end
2025-02-03 14:56:12,015 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) new JtaTransactionWrapper
2025-02-03 14:56:12,015 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) was existing? false
2025-02-03 14:56:12,015 DEBUG [org.keycloak.services.scheduled.ScheduledTaskRunner] (Timer-0) Executed scheduled task PropagateLastSessionRefreshTask
2025-02-03 14:56:12,015 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper  commit
2025-02-03 14:56:12,015 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper end
2025-02-03 14:56:17,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) new JtaTransactionWrapper
2025-02-03 14:56:17,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) was existing? false
2025-02-03 14:56:17,014 DEBUG [org.keycloak.services.scheduled.ScheduledTaskRunner] (Timer-0) Executed scheduled task PropagateLastSessionRefreshTask
2025-02-03 14:56:17,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper  commit
2025-02-03 14:56:17,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper end
2025-02-03 14:56:22,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) new JtaTransactionWrapper
2025-02-03 14:56:22,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) was existing? false
2025-02-03 14:56:22,014 DEBUG [org.keycloak.services.scheduled.ScheduledTaskRunner] (Timer-0) Executed scheduled task PropagateLastSessionRefreshTask
2025-02-03 14:56:22,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper  commit
2025-02-03 14:56:22,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper end
2025-02-03 14:56:27,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) new JtaTransactionWrapper
2025-02-03 14:56:27,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) was existing? false
2025-02-03 14:56:27,015 DEBUG [org.keycloak.models.sessions.infinispan.changes.sessions.PersisterLastSessionRefreshStore] (Timer-0) Updating 0 userSessions with lastSessionRefresh: 1738594527
2025-02-03 14:56:27,015 DEBUG [org.keycloak.services.scheduled.ScheduledTaskRunner] (Timer-0) Executed scheduled task PropagateLastSessionRefreshTask
2025-02-03 14:56:27,015 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper  commit
2025-02-03 14:56:27,015 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper end
2025-02-03 14:56:32,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) new JtaTransactionWrapper
2025-02-03 14:56:32,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) was existing? false
2025-02-03 14:56:32,014 DEBUG [org.keycloak.services.scheduled.ScheduledTaskRunner] (Timer-0) Executed scheduled task PropagateLastSessionRefreshTask
2025-02-03 14:56:32,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper  commit
2025-02-03 14:56:32,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper end
2025-02-03 14:56:37,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) new JtaTransactionWrapper
2025-02-03 14:56:37,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) was existing? false
2025-02-03 14:56:37,014 DEBUG [org.keycloak.services.scheduled.ScheduledTaskRunner] (Timer-0) Executed scheduled task PropagateLastSessionRefreshTask
2025-02-03 14:56:37,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper  commit
2025-02-03 14:56:37,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper end
2025-02-03 14:56:42,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) new JtaTransactionWrapper
2025-02-03 14:56:42,014 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) was existing? false
2025-02-03 14:56:42,014 DEBUG [org.keycloak.services.scheduled.ScheduledTaskRunner] (Timer-0) Executed scheduled task PropagateLastSessionRefreshTask
2025-02-03 14:56:42,015 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper  commit
2025-02-03 14:56:42,015 DEBUG [org.keycloak.transaction.JtaTransactionWrapper] (Timer-0) JtaTransactionWrapper end