. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v3.5.5)
2026-08-10T16:42:28.807Z INFO 1901 --- [ Test worker] n.h.h.h.o.s.d.DemoDataScenarioTest : Starting DemoDataScenarioTest using Java 21.0.11 with PID 1901 (started by root in /home/tallyman/hs.hsadmin.ng/.git/gittally/worktrees/mihoe_ci-build-performance-improvements-8da83bafd50c)
2026-08-10T16:42:28.807Z INFO 1901 --- [ Test worker] n.h.h.h.o.s.d.DemoDataScenarioTest : The following 1 profile is active: "fake-jwt"
2026-08-10T16:42:29.230Z INFO 1901 --- [ Test worker] .s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2026-08-10T16:42:29.304Z INFO 1901 --- [ Test worker] .s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository scanning in 71 ms. Found 35 JPA repository interfaces.
2026-08-10T16:42:29.380Z INFO 1901 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat initialized with port 0 (http)
2026-08-10T16:42:29.381Z INFO 1901 --- [ Test worker] o.apache.catalina.core.StandardService : Starting service [Tomcat]
2026-08-10T16:42:29.381Z INFO 1901 --- [ Test worker] o.apache.catalina.core.StandardEngine : Starting Servlet engine: [Apache Tomcat/10.1.44]
2026-08-10T16:42:29.390Z INFO 1901 --- [ Test worker] o.a.c.c.C.[Tomcat-2].[localhost].[/] : Initializing Spring embedded WebApplicationContext
2026-08-10T16:42:29.390Z INFO 1901 --- [ Test worker] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 580 ms
2026-08-10T16:42:29.439Z INFO 1901 --- [ Test worker] com.zaxxer.hikari.HikariDataSource : HikariPool-2 - Starting...
2026-08-10T16:42:29.440Z INFO 1901 --- [ Test worker] tc.postgres:17.7-trixie : Creating container for image: postgres:17.7-trixie
2026-08-10T16:42:29.557Z INFO 1901 --- [ Test worker] tc.postgres:17.7-trixie : Container postgres:17.7-trixie is starting: 475a93c304ff1e473e8a488ff9e7703030b0a6ccb0e531b02e201c191541d688
2026-08-10T16:42:30.781Z INFO 1901 --- [ Test worker] tc.postgres:17.7-trixie : Container postgres:17.7-trixie started in PT1.340748299S
2026-08-10T16:42:30.781Z INFO 1901 --- [ Test worker] tc.postgres:17.7-trixie : Container is started (JDBC URL: jdbc:postgresql://localhost:32905/test?loggerLevel=OFF)
2026-08-10T16:42:30.811Z INFO 1901 --- [ Test worker] com.zaxxer.hikari.pool.HikariPool : HikariPool-2 - Added connection org.testcontainers.jdbc.ConnectionWrapper@5218c342
2026-08-10T16:42:30.811Z INFO 1901 --- [ Test worker] com.zaxxer.hikari.HikariDataSource : HikariPool-2 - Start completed.
2026-08-10T16:42:31.145Z WARN 1901 --- [ Test worker] liquibase.executor : view "tx_journal_v" does not exist, skipping
2026-08-10T16:42:31.292Z WARN 1901 --- [ Test worker] liquibase.executor : trigger "delete_grants_of_subject_tg" for relation "rbac.subject" does not exist, skipping
2026-08-10T16:42:31.293Z WARN 1901 --- [ Test worker] liquibase.executor : trigger "delete_reference_of_subject_tg" for relation "rbac.subject" does not exist, skipping
2026-08-10T16:42:31.316Z WARN 1901 --- [ Test worker] liquibase.executor : trigger "subject_default_organization_tg" for relation "rbac.subject" does not exist, skipping
2026-08-10T16:42:31.344Z WARN 1901 --- [ Test worker] liquibase.executor : function rbac.determinecurrentsubjectorassumedrolesuuids(uuid,text) does not exist, skipping
2026-08-10T16:42:31.352Z WARN 1901 --- [ Test worker] liquibase.executor : procedure base.contextdefined(pg_catalog.varchar,text,pg_catalog.varchar,pg_catalog.varchar) does not exist, skipping
2026-08-10T16:42:31.352Z WARN 1901 --- [ Test worker] liquibase.executor : procedure base.definecontext(pg_catalog.varchar,text,pg_catalog.varchar,text) does not exist, skipping
2026-08-10T16:42:31.362Z WARN 1901 --- [ Test worker] liquibase.executor : view "role_ev" does not exist, skipping
2026-08-10T16:42:31.373Z WARN 1901 --- [ Test worker] liquibase.executor : view "grant_ev" does not exist, skipping
2026-08-10T16:42:31.390Z WARN 1901 --- [ Test worker] liquibase.executor : view "subject_ev" does not exist, skipping
2026-08-10T16:42:31.405Z WARN 1901 --- [ Test worker] liquibase.executor : function rbac.upsert_subject(uuid,pg_catalog.varchar,rbac.subjecttype) does not exist, skipping
2026-08-10T16:42:31.405Z WARN 1901 --- [ Test worker] liquibase.executor : function rbac.upsert_subject(uuid,pg_catalog.varchar,rbac.subjecttype,pg_catalog.bool) does not exist, skipping
2026-08-10T16:42:31.405Z WARN 1901 --- [ Test worker] liquibase.executor : function rbac.upsert_subject(uuid,pg_catalog.varchar,pg_catalog.varchar,rbac.subjecttype) does not exist, skipping
2026-08-10T16:42:31.450Z WARN 1901 --- [ Test worker] liquibase.executor : view "statistics_v" does not exist, skipping
2026-08-10T16:42:31.471Z WARN 1901 --- [ Test worker] liquibase.executor : there is already a transaction in progress
2026-08-10T16:42:31.471Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: initializing table "rbac.global", , , []
2026-08-10T16:42:31.475Z WARN 1901 --- [ Test worker] liquibase.executor : there is already a transaction in progress
2026-08-10T16:42:31.475Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: creating role:rbac.global#global:ADMIN, , , []
2026-08-10T16:42:31.479Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: creating role:rbac.global#global:guest, , , []
2026-08-10T16:42:31.484Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: renaming seed subjects to realm-prefixed format, , , []
2026-08-10T16:42:31.488Z WARN 1901 --- [ Test worker] liquibase.executor : constraint "check_valid_user_subject_name" of relation "subject" does not exist, skipping
2026-08-10T16:42:31.488Z WARN 1901 --- [ Test worker] liquibase.executor : constraint "check_valid_group_subject_name" of relation "subject" does not exist, skipping
2026-08-10T16:42:31.488Z WARN 1901 --- [ Test worker] liquibase.executor : constraint "check_valid_api_key_subject_name" of relation "subject" does not exist, skipping
2026-08-10T16:42:31.494Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: back-filling subject organization from the name prefix, , , []
2026-08-10T16:42:31.504Z WARN 1901 --- [ Test worker] liquibase.executor : constraint "api_key_scope_scope_format" of relation "api_key_scope" does not exist, skipping
2026-08-10T16:42:31.509Z WARN 1901 --- [ Test worker] liquibase.executor : function rbac.create_api_key(uuid,pg_catalog.varchar,pg_catalog.varchar[]) does not exist, skipping
2026-08-10T16:42:31.557Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T16:42:31.601Z WARN 1901 --- [ Test worker] liquibase.executor : constraint "relation_check_debitor_anchor_person" of relation "relation" does not exist, skipping
2026-08-10T16:42:31.601Z WARN 1901 --- [ Test worker] liquibase.executor : function hs_office.relation_check_debitor_anchor_partner() does not exist, skipping
2026-08-10T16:42:31.604Z WARN 1901 --- [ Test worker] liquibase.executor : trigger "relation_enforce_new_mark_by_global_admin_tg" for relation "hs_office.relation" does not exist, skipping
2026-08-10T16:42:31.621Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.relation permissions for pre-exising hs_office.person rows, , , []
2026-08-10T16:42:31.666Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.partner permissions for pre-exising rbac.global rows, , , []
2026-08-10T16:42:31.688Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.partner_details permissions for pre-exising rbac.global rows, , , []
2026-08-10T16:42:31.710Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T16:42:31.758Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.debitor permissions for pre-exising rbac.global rows, , , []
2026-08-10T16:42:31.788Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.sepamandate permissions for pre-exising hs_office.relation rows, , , []
2026-08-10T16:42:31.807Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T16:42:31.833Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.membership permissions for pre-exising rbac.global rows, , , []
2026-08-10T16:42:31.854Z WARN 1901 --- [ Test worker] liquibase.executor : constraint "check_positive_total_shares_count" of relation "coopsharetx" does not exist, skipping
2026-08-10T16:42:31.854Z WARN 1901 --- [ Test worker] liquibase.executor : function hs_office.coopsharestx_check_positive_total() does not exist, skipping
2026-08-10T16:42:31.854Z WARN 1901 --- [ Test worker] liquibase.executor : trigger "positive_total_shares_count_tg" for relation "hs_office.coopsharetx" does not exist, skipping
2026-08-10T16:42:31.866Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.coopsharetx permissions for pre-exising hs_office.membership rows, , , []
2026-08-10T16:42:31.884Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T16:42:31.915Z WARN 1901 --- [ Test worker] liquibase.executor : constraint "coopassetstx_check_positive_total" of relation "coopassettx" does not exist, skipping
2026-08-10T16:42:31.915Z WARN 1901 --- [ Test worker] liquibase.executor : function hs_office.coopassetstx_check_positive_total() does not exist, skipping
2026-08-10T16:42:31.915Z WARN 1901 --- [ Test worker] liquibase.executor : trigger "positive_total_assets_count_tg" for relation "hs_office.coopassettx" does not exist, skipping
2026-08-10T16:42:31.934Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.coopassettx permissions for pre-exising hs_office.membership rows, , , []
2026-08-10T16:42:31.963Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T16:42:31.996Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_booking.project permissions for pre-exising hs_office.relation rows, , , []
2026-08-10T16:42:32.045Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_booking.item permissions for pre-exising rbac.global rows, , , []
2026-08-10T16:42:32.045Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_booking.item permissions for pre-exising hs_booking.project rows, , , []
2026-08-10T16:42:32.113Z WARN 1901 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T16:42:32.122Z WARN 1901 --- [ Test worker] liquibase.executor : view "hs_statistics_v" does not exist, skipping
2026-08-10T16:42:32.162Z WARN 1901 --- [ Test worker] liquibase.executor : view "account_ev" does not exist, skipping
2026-08-10T16:42:32.168Z WARN 1901 --- [ Test worker] liquibase.executor : trigger "assert_account_subject_is_user_tg" for relation "hs_accounts.account" does not exist, skipping
2026-08-10T16:42:32.239Z INFO 1901 --- [ Test worker] o.hibernate.jpa.internal.util.LogHelper : HHH000204: Processing PersistenceUnitInfo [name: default]
2026-08-10T16:42:32.241Z INFO 1901 --- [ Test worker] o.h.c.internal.RegionFactoryInitiator : HHH000026: Second-level cache disabled
2026-08-10T16:42:32.244Z INFO 1901 --- [ 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-10T16:42:32.250Z INFO 1901 --- [ Test worker] o.s.o.j.p.SpringPersistenceUnitInfo : No LoadTimeWeaver setup: ignoring JPA class transformer
2026-08-10T16:42:32.286Z WARN 1901 --- [ 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-10T16:42:32.286Z WARN 1901 --- [ 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-10T16:42:32.292Z WARN 1901 --- [ 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-10T16:42:32.293Z WARN 1901 --- [ 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-10T16:42:32.493Z INFO 1901 --- [ 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-10T16:42:32.493Z INFO 1901 --- [ Test worker] j.LocalContainerEntityManagerFactoryBean : Initialized JPA EntityManagerFactory for persistence unit 'default'
2026-08-10T16:42:32.855Z WARN 1901 --- [ 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-10T16:42:33.176Z INFO 1901 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat started on port 44411 (http) with context path '/'
2026-08-10T16:42:33.202Z INFO 1901 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat initialized with port 0 (http)
2026-08-10T16:42:33.203Z INFO 1901 --- [ Test worker] o.apache.catalina.core.StandardService : Starting service [Tomcat]
2026-08-10T16:42:33.203Z INFO 1901 --- [ Test worker] o.apache.catalina.core.StandardEngine : Starting Servlet engine: [Apache Tomcat/10.1.44]
2026-08-10T16:42:33.212Z INFO 1901 --- [ Test worker] o.a.c.c.C.[Tomcat-3].[localhost].[/] : Initializing Spring embedded WebApplicationContext
2026-08-10T16:42:33.212Z INFO 1901 --- [ Test worker] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 35 ms
2026-08-10T16:42:33.217Z INFO 1901 --- [ Test worker] o.s.b.a.e.web.EndpointLinksResolver : Exposing 5 endpoints beneath base path '/actuator'
2026-08-10T16:42:33.242Z INFO 1901 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat started on port 32879 (http) with context path '/'
2026-08-10T16:42:33.246Z INFO 1901 --- [ Test worker] n.h.h.h.o.s.d.DemoDataScenarioTest : Started DemoDataScenarioTest in 4.462 seconds (process running for 38.761)
2026-08-10T16:42:33.252Z INFO 1901 --- [ Test worker] n.h.h.c.ProvisioningApiKeyBootstrap : provisioned the provisioning API-key subject 'hsadminng.provisioning.key' with the global ADMIN role
2026-08-10T16:42:33.269Z INFO 1901 --- [o-auto-3-exec-1] o.a.c.c.C.[Tomcat-2].[localhost].[/] : Initializing Spring DispatcherServlet 'dispatcherServlet'
2026-08-10T16:42:33.269Z INFO 1901 --- [o-auto-3-exec-1] o.s.web.servlet.DispatcherServlet : Initializing Servlet 'dispatcherServlet'
2026-08-10T16:42:33.271Z INFO 1901 --- [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_ci-build-performance-improvements-8da83bafd50c/build/scenario-reports/9999:%20Generate%20Demo%20Dataset.md
DEBUG LOG: file:///home/tallyman/hs.hsadmin.ng/.git/gittally/worktrees/mihoe_ci-build-performance-improvements-8da83bafd50c/build/scenario-reports/.last-debug-log.md