. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v3.5.5)
2026-08-10T13:37:56.377Z INFO 1863 --- [ Test worker] n.h.h.h.o.s.d.DemoDataScenarioTest : Starting DemoDataScenarioTest using Java 21.0.11 with PID 1863 (started by root in /home/tallyman/hs.hsadmin.ng/.git/gittally/worktrees/mihoe_base-tx-context-seqTxId-performance-3915db90eedb)
2026-08-10T13:37:56.377Z INFO 1863 --- [ Test worker] n.h.h.h.o.s.d.DemoDataScenarioTest : The following 1 profile is active: "fake-jwt"
2026-08-10T13:37:56.704Z INFO 1863 --- [ Test worker] .s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2026-08-10T13:37:56.769Z INFO 1863 --- [ Test worker] .s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository scanning in 63 ms. Found 35 JPA repository interfaces.
2026-08-10T13:37:56.841Z INFO 1863 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat initialized with port 0 (http)
2026-08-10T13:37:56.842Z INFO 1863 --- [ Test worker] o.apache.catalina.core.StandardService : Starting service [Tomcat]
2026-08-10T13:37:56.842Z INFO 1863 --- [ Test worker] o.apache.catalina.core.StandardEngine : Starting Servlet engine: [Apache Tomcat/10.1.44]
2026-08-10T13:37:56.851Z INFO 1863 --- [ Test worker] o.a.c.c.C.[Tomcat-2].[localhost].[/] : Initializing Spring embedded WebApplicationContext
2026-08-10T13:37:56.851Z INFO 1863 --- [ Test worker] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 472 ms
2026-08-10T13:37:56.900Z INFO 1863 --- [ Test worker] com.zaxxer.hikari.HikariDataSource : HikariPool-2 - Starting...
2026-08-10T13:37:56.901Z INFO 1863 --- [ Test worker] tc.postgres:17.7-trixie : Creating container for image: postgres:17.7-trixie
2026-08-10T13:37:57.043Z INFO 1863 --- [ Test worker] tc.postgres:17.7-trixie : Container postgres:17.7-trixie is starting: 0bb7d75f8e7e11b79c69fcd57b40d787640f9bed37ff03190ef1ed2333eb2fd9
2026-08-10T13:37:58.294Z INFO 1863 --- [ Test worker] tc.postgres:17.7-trixie : Container postgres:17.7-trixie started in PT1.392868683S
2026-08-10T13:37:58.294Z INFO 1863 --- [ Test worker] tc.postgres:17.7-trixie : Container is started (JDBC URL: jdbc:postgresql://localhost:32795/test?loggerLevel=OFF)
2026-08-10T13:37:58.322Z INFO 1863 --- [ Test worker] com.zaxxer.hikari.pool.HikariPool : HikariPool-2 - Added connection org.testcontainers.jdbc.ConnectionWrapper@6538997a
2026-08-10T13:37:58.322Z INFO 1863 --- [ Test worker] com.zaxxer.hikari.HikariDataSource : HikariPool-2 - Start completed.
2026-08-10T13:37:58.782Z WARN 1863 --- [ Test worker] liquibase.executor : view "tx_journal_v" does not exist, skipping
2026-08-10T13:37:58.909Z WARN 1863 --- [ Test worker] liquibase.executor : trigger "delete_grants_of_subject_tg" for relation "rbac.subject" does not exist, skipping
2026-08-10T13:37:58.910Z WARN 1863 --- [ Test worker] liquibase.executor : trigger "delete_reference_of_subject_tg" for relation "rbac.subject" does not exist, skipping
2026-08-10T13:37:58.929Z WARN 1863 --- [ Test worker] liquibase.executor : trigger "subject_default_organization_tg" for relation "rbac.subject" does not exist, skipping
2026-08-10T13:37:58.957Z WARN 1863 --- [ Test worker] liquibase.executor : function rbac.determinecurrentsubjectorassumedrolesuuids(uuid,text) does not exist, skipping
2026-08-10T13:37:58.965Z WARN 1863 --- [ Test worker] liquibase.executor : procedure base.contextdefined(pg_catalog.varchar,text,pg_catalog.varchar,pg_catalog.varchar) does not exist, skipping
2026-08-10T13:37:58.965Z WARN 1863 --- [ Test worker] liquibase.executor : procedure base.definecontext(pg_catalog.varchar,text,pg_catalog.varchar,text) does not exist, skipping
2026-08-10T13:37:58.975Z WARN 1863 --- [ Test worker] liquibase.executor : view "role_ev" does not exist, skipping
2026-08-10T13:37:58.985Z WARN 1863 --- [ Test worker] liquibase.executor : view "grant_ev" does not exist, skipping
2026-08-10T13:37:59.000Z WARN 1863 --- [ Test worker] liquibase.executor : view "subject_ev" does not exist, skipping
2026-08-10T13:37:59.016Z WARN 1863 --- [ Test worker] liquibase.executor : function rbac.upsert_subject(uuid,pg_catalog.varchar,rbac.subjecttype) does not exist, skipping
2026-08-10T13:37:59.016Z WARN 1863 --- [ Test worker] liquibase.executor : function rbac.upsert_subject(uuid,pg_catalog.varchar,rbac.subjecttype,pg_catalog.bool) does not exist, skipping
2026-08-10T13:37:59.016Z WARN 1863 --- [ Test worker] liquibase.executor : function rbac.upsert_subject(uuid,pg_catalog.varchar,pg_catalog.varchar,rbac.subjecttype) does not exist, skipping
2026-08-10T13:37:59.059Z WARN 1863 --- [ Test worker] liquibase.executor : view "statistics_v" does not exist, skipping
2026-08-10T13:37:59.080Z WARN 1863 --- [ Test worker] liquibase.executor : there is already a transaction in progress
2026-08-10T13:37:59.080Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: initializing table "rbac.global", , , []
2026-08-10T13:37:59.084Z WARN 1863 --- [ Test worker] liquibase.executor : there is already a transaction in progress
2026-08-10T13:37:59.084Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: creating role:rbac.global#global:ADMIN, , , []
2026-08-10T13:37:59.089Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: creating role:rbac.global#global:guest, , , []
2026-08-10T13:37:59.093Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: renaming seed subjects to realm-prefixed format, , , []
2026-08-10T13:37:59.097Z WARN 1863 --- [ Test worker] liquibase.executor : constraint "check_valid_user_subject_name" of relation "subject" does not exist, skipping
2026-08-10T13:37:59.097Z WARN 1863 --- [ Test worker] liquibase.executor : constraint "check_valid_group_subject_name" of relation "subject" does not exist, skipping
2026-08-10T13:37:59.097Z WARN 1863 --- [ Test worker] liquibase.executor : constraint "check_valid_api_key_subject_name" of relation "subject" does not exist, skipping
2026-08-10T13:37:59.103Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: back-filling subject organization from the name prefix, , , []
2026-08-10T13:37:59.114Z WARN 1863 --- [ Test worker] liquibase.executor : constraint "api_key_scope_scope_format" of relation "api_key_scope" does not exist, skipping
2026-08-10T13:37:59.119Z WARN 1863 --- [ Test worker] liquibase.executor : function rbac.create_api_key(uuid,pg_catalog.varchar,pg_catalog.varchar[]) does not exist, skipping
2026-08-10T13:37:59.174Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T13:37:59.221Z WARN 1863 --- [ Test worker] liquibase.executor : constraint "relation_check_debitor_anchor_person" of relation "relation" does not exist, skipping
2026-08-10T13:37:59.221Z WARN 1863 --- [ Test worker] liquibase.executor : function hs_office.relation_check_debitor_anchor_partner() does not exist, skipping
2026-08-10T13:37:59.225Z WARN 1863 --- [ Test worker] liquibase.executor : trigger "relation_enforce_new_mark_by_global_admin_tg" for relation "hs_office.relation" does not exist, skipping
2026-08-10T13:37:59.245Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.relation permissions for pre-exising hs_office.person rows, , , []
2026-08-10T13:37:59.303Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.partner permissions for pre-exising rbac.global rows, , , []
2026-08-10T13:37:59.337Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.partner_details permissions for pre-exising rbac.global rows, , , []
2026-08-10T13:37:59.368Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T13:37:59.425Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.debitor permissions for pre-exising rbac.global rows, , , []
2026-08-10T13:37:59.466Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.sepamandate permissions for pre-exising hs_office.relation rows, , , []
2026-08-10T13:37:59.488Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T13:37:59.516Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.membership permissions for pre-exising rbac.global rows, , , []
2026-08-10T13:37:59.543Z WARN 1863 --- [ Test worker] liquibase.executor : constraint "check_positive_total_shares_count" of relation "coopsharetx" does not exist, skipping
2026-08-10T13:37:59.543Z WARN 1863 --- [ Test worker] liquibase.executor : function hs_office.coopsharestx_check_positive_total() does not exist, skipping
2026-08-10T13:37:59.543Z WARN 1863 --- [ Test worker] liquibase.executor : trigger "positive_total_shares_count_tg" for relation "hs_office.coopsharetx" does not exist, skipping
2026-08-10T13:37:59.556Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.coopsharetx permissions for pre-exising hs_office.membership rows, , , []
2026-08-10T13:37:59.576Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T13:37:59.601Z WARN 1863 --- [ Test worker] liquibase.executor : constraint "coopassetstx_check_positive_total" of relation "coopassettx" does not exist, skipping
2026-08-10T13:37:59.601Z WARN 1863 --- [ Test worker] liquibase.executor : function hs_office.coopassetstx_check_positive_total() does not exist, skipping
2026-08-10T13:37:59.601Z WARN 1863 --- [ Test worker] liquibase.executor : trigger "positive_total_assets_count_tg" for relation "hs_office.coopassettx" does not exist, skipping
2026-08-10T13:37:59.613Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.coopassettx permissions for pre-exising hs_office.membership rows, , , []
2026-08-10T13:37:59.636Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T13:37:59.673Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_booking.project permissions for pre-exising hs_office.relation rows, , , []
2026-08-10T13:37:59.729Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_booking.item permissions for pre-exising rbac.global rows, , , []
2026-08-10T13:37:59.729Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_booking.item permissions for pre-exising hs_booking.project rows, , , []
2026-08-10T13:37:59.803Z WARN 1863 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T13:37:59.813Z WARN 1863 --- [ Test worker] liquibase.executor : view "hs_statistics_v" does not exist, skipping
2026-08-10T13:37:59.824Z WARN 1863 --- [ Test worker] liquibase.executor : view "account_ev" does not exist, skipping
2026-08-10T13:37:59.835Z WARN 1863 --- [ Test worker] liquibase.executor : trigger "assert_account_subject_is_user_tg" for relation "hs_accounts.account" does not exist, skipping
2026-08-10T13:37:59.923Z INFO 1863 --- [ Test worker] o.hibernate.jpa.internal.util.LogHelper : HHH000204: Processing PersistenceUnitInfo [name: default]
2026-08-10T13:37:59.925Z INFO 1863 --- [ Test worker] o.h.c.internal.RegionFactoryInitiator : HHH000026: Second-level cache disabled
2026-08-10T13:37:59.929Z INFO 1863 --- [ 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-10T13:37:59.934Z INFO 1863 --- [ Test worker] o.s.o.j.p.SpringPersistenceUnitInfo : No LoadTimeWeaver setup: ignoring JPA class transformer
2026-08-10T13:37:59.964Z WARN 1863 --- [ 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-10T13:37:59.965Z WARN 1863 --- [ 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-10T13:37:59.970Z WARN 1863 --- [ 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-10T13:37:59.971Z WARN 1863 --- [ 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-10T13:38:00.184Z INFO 1863 --- [ 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-10T13:38:00.184Z INFO 1863 --- [ Test worker] j.LocalContainerEntityManagerFactoryBean : Initialized JPA EntityManagerFactory for persistence unit 'default'
2026-08-10T13:38:00.591Z WARN 1863 --- [ 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-10T13:38:00.936Z INFO 1863 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat started on port 42815 (http) with context path '/'
2026-08-10T13:38:00.965Z INFO 1863 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat initialized with port 0 (http)
2026-08-10T13:38:00.965Z INFO 1863 --- [ Test worker] o.apache.catalina.core.StandardService : Starting service [Tomcat]
2026-08-10T13:38:00.966Z INFO 1863 --- [ Test worker] o.apache.catalina.core.StandardEngine : Starting Servlet engine: [Apache Tomcat/10.1.44]
2026-08-10T13:38:00.974Z INFO 1863 --- [ Test worker] o.a.c.c.C.[Tomcat-3].[localhost].[/] : Initializing Spring embedded WebApplicationContext
2026-08-10T13:38:00.974Z INFO 1863 --- [ Test worker] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 36 ms
2026-08-10T13:38:00.981Z INFO 1863 --- [ Test worker] o.s.b.a.e.web.EndpointLinksResolver : Exposing 5 endpoints beneath base path '/actuator'
2026-08-10T13:38:01.005Z INFO 1863 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat started on port 46637 (http) with context path '/'
2026-08-10T13:38:01.023Z INFO 1863 --- [ Test worker] n.h.h.h.o.s.d.DemoDataScenarioTest : Started DemoDataScenarioTest in 4.672 seconds (process running for 38.338)
2026-08-10T13:38:01.029Z INFO 1863 --- [ Test worker] n.h.h.c.ProvisioningApiKeyBootstrap : provisioned the provisioning API-key subject 'hsadminng.provisioning.key' with the global ADMIN role
2026-08-10T13:38:01.044Z INFO 1863 --- [o-auto-3-exec-1] o.a.c.c.C.[Tomcat-2].[localhost].[/] : Initializing Spring DispatcherServlet 'dispatcherServlet'
2026-08-10T13:38:01.044Z INFO 1863 --- [o-auto-3-exec-1] o.s.web.servlet.DispatcherServlet : Initializing Servlet 'dispatcherServlet'
2026-08-10T13:38:01.046Z INFO 1863 --- [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