. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v3.5.5)
2026-08-10T15:08:34.387Z INFO 1766 --- [ Test worker] n.h.h.h.o.s.d.DemoDataScenarioTest : Starting DemoDataScenarioTest using Java 21.0.11 with PID 1766 (started by root in /home/tallyman/hs.hsadmin.ng/.git/gittally/worktrees/mihoe_base-tx-context-seqTxId-performance-3915db90eedb)
2026-08-10T15:08:34.387Z INFO 1766 --- [ Test worker] n.h.h.h.o.s.d.DemoDataScenarioTest : The following 1 profile is active: "fake-jwt"
2026-08-10T15:08:34.754Z INFO 1766 --- [ Test worker] .s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2026-08-10T15:08:34.842Z INFO 1766 --- [ Test worker] .s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository scanning in 87 ms. Found 35 JPA repository interfaces.
2026-08-10T15:08:34.935Z INFO 1766 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat initialized with port 0 (http)
2026-08-10T15:08:34.937Z INFO 1766 --- [ Test worker] o.apache.catalina.core.StandardService : Starting service [Tomcat]
2026-08-10T15:08:34.937Z INFO 1766 --- [ Test worker] o.apache.catalina.core.StandardEngine : Starting Servlet engine: [Apache Tomcat/10.1.44]
2026-08-10T15:08:34.949Z INFO 1766 --- [ Test worker] o.a.c.c.C.[Tomcat-2].[localhost].[/] : Initializing Spring embedded WebApplicationContext
2026-08-10T15:08:34.949Z INFO 1766 --- [ Test worker] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 559 ms
2026-08-10T15:08:35.014Z INFO 1766 --- [ Test worker] com.zaxxer.hikari.HikariDataSource : HikariPool-2 - Starting...
2026-08-10T15:08:35.016Z INFO 1766 --- [ Test worker] tc.postgres:17.7-trixie : Creating container for image: postgres:17.7-trixie
2026-08-10T15:08:35.166Z INFO 1766 --- [ Test worker] tc.postgres:17.7-trixie : Container postgres:17.7-trixie is starting: fc0b028242d484d26ed9aefb7483d3056e9dfcd211db39b605e7ef9c4e20ef70
2026-08-10T15:08:36.519Z INFO 1766 --- [ Test worker] tc.postgres:17.7-trixie : Container postgres:17.7-trixie started in PT1.503039396S
2026-08-10T15:08:36.520Z INFO 1766 --- [ Test worker] tc.postgres:17.7-trixie : Container is started (JDBC URL: jdbc:postgresql://localhost:32849/test?loggerLevel=OFF)
2026-08-10T15:08:36.547Z INFO 1766 --- [ Test worker] com.zaxxer.hikari.pool.HikariPool : HikariPool-2 - Added connection org.testcontainers.jdbc.ConnectionWrapper@3ff84c0a
2026-08-10T15:08:36.547Z INFO 1766 --- [ Test worker] com.zaxxer.hikari.HikariDataSource : HikariPool-2 - Start completed.
2026-08-10T15:08:36.987Z WARN 1766 --- [ Test worker] liquibase.executor : view "tx_journal_v" does not exist, skipping
2026-08-10T15:08:37.184Z WARN 1766 --- [ Test worker] liquibase.executor : trigger "delete_grants_of_subject_tg" for relation "rbac.subject" does not exist, skipping
2026-08-10T15:08:37.185Z WARN 1766 --- [ Test worker] liquibase.executor : trigger "delete_reference_of_subject_tg" for relation "rbac.subject" does not exist, skipping
2026-08-10T15:08:37.207Z WARN 1766 --- [ Test worker] liquibase.executor : trigger "subject_default_organization_tg" for relation "rbac.subject" does not exist, skipping
2026-08-10T15:08:37.239Z WARN 1766 --- [ Test worker] liquibase.executor : function rbac.determinecurrentsubjectorassumedrolesuuids(uuid,text) does not exist, skipping
2026-08-10T15:08:37.248Z WARN 1766 --- [ Test worker] liquibase.executor : procedure base.contextdefined(pg_catalog.varchar,text,pg_catalog.varchar,pg_catalog.varchar) does not exist, skipping
2026-08-10T15:08:37.248Z WARN 1766 --- [ Test worker] liquibase.executor : procedure base.definecontext(pg_catalog.varchar,text,pg_catalog.varchar,text) does not exist, skipping
2026-08-10T15:08:37.259Z WARN 1766 --- [ Test worker] liquibase.executor : view "role_ev" does not exist, skipping
2026-08-10T15:08:37.272Z WARN 1766 --- [ Test worker] liquibase.executor : view "grant_ev" does not exist, skipping
2026-08-10T15:08:37.291Z WARN 1766 --- [ Test worker] liquibase.executor : view "subject_ev" does not exist, skipping
2026-08-10T15:08:37.308Z WARN 1766 --- [ Test worker] liquibase.executor : function rbac.upsert_subject(uuid,pg_catalog.varchar,rbac.subjecttype) does not exist, skipping
2026-08-10T15:08:37.308Z WARN 1766 --- [ Test worker] liquibase.executor : function rbac.upsert_subject(uuid,pg_catalog.varchar,rbac.subjecttype,pg_catalog.bool) does not exist, skipping
2026-08-10T15:08:37.308Z WARN 1766 --- [ Test worker] liquibase.executor : function rbac.upsert_subject(uuid,pg_catalog.varchar,pg_catalog.varchar,rbac.subjecttype) does not exist, skipping
2026-08-10T15:08:37.357Z WARN 1766 --- [ Test worker] liquibase.executor : view "statistics_v" does not exist, skipping
2026-08-10T15:08:37.379Z WARN 1766 --- [ Test worker] liquibase.executor : there is already a transaction in progress
2026-08-10T15:08:37.379Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: initializing table "rbac.global", , , []
2026-08-10T15:08:37.385Z WARN 1766 --- [ Test worker] liquibase.executor : there is already a transaction in progress
2026-08-10T15:08:37.385Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: creating role:rbac.global#global:ADMIN, , , []
2026-08-10T15:08:37.390Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: creating role:rbac.global#global:guest, , , []
2026-08-10T15:08:37.395Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: renaming seed subjects to realm-prefixed format, , , []
2026-08-10T15:08:37.400Z WARN 1766 --- [ Test worker] liquibase.executor : constraint "check_valid_user_subject_name" of relation "subject" does not exist, skipping
2026-08-10T15:08:37.400Z WARN 1766 --- [ Test worker] liquibase.executor : constraint "check_valid_group_subject_name" of relation "subject" does not exist, skipping
2026-08-10T15:08:37.400Z WARN 1766 --- [ Test worker] liquibase.executor : constraint "check_valid_api_key_subject_name" of relation "subject" does not exist, skipping
2026-08-10T15:08:37.406Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: back-filling subject organization from the name prefix, , , []
2026-08-10T15:08:37.418Z WARN 1766 --- [ Test worker] liquibase.executor : constraint "api_key_scope_scope_format" of relation "api_key_scope" does not exist, skipping
2026-08-10T15:08:37.424Z WARN 1766 --- [ Test worker] liquibase.executor : function rbac.create_api_key(uuid,pg_catalog.varchar,pg_catalog.varchar[]) does not exist, skipping
2026-08-10T15:08:37.481Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T15:08:37.530Z WARN 1766 --- [ Test worker] liquibase.executor : constraint "relation_check_debitor_anchor_person" of relation "relation" does not exist, skipping
2026-08-10T15:08:37.530Z WARN 1766 --- [ Test worker] liquibase.executor : function hs_office.relation_check_debitor_anchor_partner() does not exist, skipping
2026-08-10T15:08:37.535Z WARN 1766 --- [ Test worker] liquibase.executor : trigger "relation_enforce_new_mark_by_global_admin_tg" for relation "hs_office.relation" does not exist, skipping
2026-08-10T15:08:37.555Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.relation permissions for pre-exising hs_office.person rows, , , []
2026-08-10T15:08:37.618Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.partner permissions for pre-exising rbac.global rows, , , []
2026-08-10T15:08:37.645Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.partner_details permissions for pre-exising rbac.global rows, , , []
2026-08-10T15:08:37.676Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T15:08:37.736Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.debitor permissions for pre-exising rbac.global rows, , , []
2026-08-10T15:08:37.773Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.sepamandate permissions for pre-exising hs_office.relation rows, , , []
2026-08-10T15:08:37.797Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T15:08:37.827Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.membership permissions for pre-exising rbac.global rows, , , []
2026-08-10T15:08:37.854Z WARN 1766 --- [ Test worker] liquibase.executor : constraint "check_positive_total_shares_count" of relation "coopsharetx" does not exist, skipping
2026-08-10T15:08:37.855Z WARN 1766 --- [ Test worker] liquibase.executor : function hs_office.coopsharestx_check_positive_total() does not exist, skipping
2026-08-10T15:08:37.855Z WARN 1766 --- [ Test worker] liquibase.executor : trigger "positive_total_shares_count_tg" for relation "hs_office.coopsharetx" does not exist, skipping
2026-08-10T15:08:37.869Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.coopsharetx permissions for pre-exising hs_office.membership rows, , , []
2026-08-10T15:08:37.893Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T15:08:37.912Z WARN 1766 --- [ Test worker] liquibase.executor : constraint "coopassetstx_check_positive_total" of relation "coopassettx" does not exist, skipping
2026-08-10T15:08:37.912Z WARN 1766 --- [ Test worker] liquibase.executor : function hs_office.coopassetstx_check_positive_total() does not exist, skipping
2026-08-10T15:08:37.912Z WARN 1766 --- [ Test worker] liquibase.executor : trigger "positive_total_assets_count_tg" for relation "hs_office.coopassettx" does not exist, skipping
2026-08-10T15:08:37.925Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.coopassettx permissions for pre-exising hs_office.membership rows, , , []
2026-08-10T15:08:37.951Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T15:08:37.989Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_booking.project permissions for pre-exising hs_office.relation rows, , , []
2026-08-10T15:08:38.041Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_booking.item permissions for pre-exising rbac.global rows, , , []
2026-08-10T15:08:38.041Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_booking.item permissions for pre-exising hs_booking.project rows, , , []
2026-08-10T15:08:38.120Z WARN 1766 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T15:08:38.130Z WARN 1766 --- [ Test worker] liquibase.executor : view "hs_statistics_v" does not exist, skipping
2026-08-10T15:08:38.138Z WARN 1766 --- [ Test worker] liquibase.executor : view "account_ev" does not exist, skipping
2026-08-10T15:08:38.145Z WARN 1766 --- [ Test worker] liquibase.executor : trigger "assert_account_subject_is_user_tg" for relation "hs_accounts.account" does not exist, skipping
2026-08-10T15:08:38.242Z INFO 1766 --- [ Test worker] o.hibernate.jpa.internal.util.LogHelper : HHH000204: Processing PersistenceUnitInfo [name: default]
2026-08-10T15:08:38.245Z INFO 1766 --- [ Test worker] o.h.c.internal.RegionFactoryInitiator : HHH000026: Second-level cache disabled
2026-08-10T15:08:38.250Z INFO 1766 --- [ Test worker] org.hibernate.orm.connections.pooling : HHH10001005: Database info:
Database JDBC URL [Connecting through datasource 'proxyDataSourceDecorator [net.ttddyy.dsproxy.support.ProxyDataSource] -> dataSource [com.zaxxer.hikari.HikariDataSource]']
Database driver: undefined/unknown
Database version: 15.5
Autocommit mode: undefined/unknown
Isolation level: undefined/unknown
Minimum pool size: undefined/unknown
Maximum pool size: undefined/unknown
2026-08-10T15:08:38.258Z INFO 1766 --- [ Test worker] o.s.o.j.p.SpringPersistenceUnitInfo : No LoadTimeWeaver setup: ignoring JPA class transformer
2026-08-10T15:08:38.300Z WARN 1766 --- [ Test worker] o.h.boot.model.internal.ToOneBinder : HHH000491: 'net.hostsharing.hsadminng.hs.office.debitor.HsOfficeDebitorEntity.partner' uses both @NotFound and FetchType.LAZY. @ManyToOne and @OneToOne associations mapped with @NotFound are forced to EAGER fetching.
2026-08-10T15:08:38.300Z WARN 1766 --- [ Test worker] o.h.boot.model.internal.ToOneBinder : HHH000491: 'net.hostsharing.hsadminng.hs.office.debitor.HsOfficeDebitorEntity.refundBankAccount' uses both @NotFound and FetchType.LAZY. @ManyToOne and @OneToOne associations mapped with @NotFound are forced to EAGER fetching.
2026-08-10T15:08:38.307Z WARN 1766 --- [ Test worker] o.h.boot.model.internal.ToOneBinder : HHH000491: 'net.hostsharing.hsadminng.hs.office.partner.HsOfficePartnerRbacEntity.details' uses both @NotFound and FetchType.LAZY. @ManyToOne and @OneToOne associations mapped with @NotFound are forced to EAGER fetching.
2026-08-10T15:08:38.309Z WARN 1766 --- [ Test worker] o.h.boot.model.internal.ToOneBinder : HHH000491: 'net.hostsharing.hsadminng.hs.office.partner.HsOfficePartnerRealEntity.details' uses both @NotFound and FetchType.LAZY. @ManyToOne and @OneToOne associations mapped with @NotFound are forced to EAGER fetching.
2026-08-10T15:08:38.541Z INFO 1766 --- [ Test worker] o.h.e.t.j.p.i.JtaPlatformInitiator : HHH000489: No JTA platform available (set 'hibernate.transaction.jta.platform' to enable JTA platform integration)
2026-08-10T15:08:38.541Z INFO 1766 --- [ Test worker] j.LocalContainerEntityManagerFactoryBean : Initialized JPA EntityManagerFactory for persistence unit 'default'
2026-08-10T15:08:39.075Z WARN 1766 --- [ Test worker] JpaBaseConfiguration$JpaWebConfiguration : spring.jpa.open-in-view is enabled by default. Therefore, database queries may be performed during view rendering. Explicitly configure spring.jpa.open-in-view to disable this warning
2026-08-10T15:08:39.548Z INFO 1766 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat started on port 38509 (http) with context path '/'
2026-08-10T15:08:39.586Z INFO 1766 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat initialized with port 0 (http)
2026-08-10T15:08:39.588Z INFO 1766 --- [ Test worker] o.apache.catalina.core.StandardService : Starting service [Tomcat]
2026-08-10T15:08:39.588Z INFO 1766 --- [ Test worker] o.apache.catalina.core.StandardEngine : Starting Servlet engine: [Apache Tomcat/10.1.44]
2026-08-10T15:08:39.599Z INFO 1766 --- [ Test worker] o.a.c.c.C.[Tomcat-3].[localhost].[/] : Initializing Spring embedded WebApplicationContext
2026-08-10T15:08:39.599Z INFO 1766 --- [ Test worker] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 47 ms
2026-08-10T15:08:39.607Z INFO 1766 --- [ Test worker] o.s.b.a.e.web.EndpointLinksResolver : Exposing 5 endpoints beneath base path '/actuator'
2026-08-10T15:08:39.633Z INFO 1766 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat started on port 36585 (http) with context path '/'
2026-08-10T15:08:39.653Z INFO 1766 --- [ Test worker] n.h.h.h.o.s.d.DemoDataScenarioTest : Started DemoDataScenarioTest in 5.288 seconds (process running for 40.261)
2026-08-10T15:08:39.664Z INFO 1766 --- [ Test worker] n.h.h.c.ProvisioningApiKeyBootstrap : provisioned the provisioning API-key subject 'hsadminng.provisioning.key' with the global ADMIN role
2026-08-10T15:08:39.690Z INFO 1766 --- [o-auto-3-exec-1] o.a.c.c.C.[Tomcat-2].[localhost].[/] : Initializing Spring DispatcherServlet 'dispatcherServlet'
2026-08-10T15:08:39.691Z INFO 1766 --- [o-auto-3-exec-1] o.s.web.servlet.DispatcherServlet : Initializing Servlet 'dispatcherServlet'
2026-08-10T15:08:39.693Z INFO 1766 --- [o-auto-3-exec-1] o.s.web.servlet.DispatcherServlet : Completed initialization in 2 ms
SCENARIO REPORT: file:///home/tallyman/hs.hsadmin.ng/.git/gittally/worktrees/mihoe_base-tx-context-seqTxId-performance-3915db90eedb/build/scenario-reports/9999:%20Generate%20Demo%20Dataset.md
DEBUG LOG: file:///home/tallyman/hs.hsadmin.ng/.git/gittally/worktrees/mihoe_base-tx-context-seqTxId-performance-3915db90eedb/build/scenario-reports/.last-debug-log.md