. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v3.5.5)
2026-08-10T16:34:25.328Z INFO 1931 --- [ Test worker] n.h.h.h.o.s.d.DemoDataScenarioTest : Starting DemoDataScenarioTest using Java 21.0.11 with PID 1931 (started by root in /home/tallyman/hs.hsadmin.ng/.git/gittally/worktrees/master-fc613b4dfd67)
2026-08-10T16:34:25.328Z INFO 1931 --- [ Test worker] n.h.h.h.o.s.d.DemoDataScenarioTest : The following 1 profile is active: "fake-jwt"
2026-08-10T16:34:25.594Z INFO 1931 --- [ Test worker] .s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2026-08-10T16:34:25.677Z INFO 1931 --- [ Test worker] .s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository scanning in 81 ms. Found 35 JPA repository interfaces.
2026-08-10T16:34:25.752Z INFO 1931 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat initialized with port 0 (http)
2026-08-10T16:34:25.753Z INFO 1931 --- [ Test worker] o.apache.catalina.core.StandardService : Starting service [Tomcat]
2026-08-10T16:34:25.753Z INFO 1931 --- [ Test worker] o.apache.catalina.core.StandardEngine : Starting Servlet engine: [Apache Tomcat/10.1.44]
2026-08-10T16:34:25.763Z INFO 1931 --- [ Test worker] o.a.c.c.C.[Tomcat-2].[localhost].[/] : Initializing Spring embedded WebApplicationContext
2026-08-10T16:34:25.763Z INFO 1931 --- [ Test worker] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 433 ms
2026-08-10T16:34:25.817Z INFO 1931 --- [ Test worker] com.zaxxer.hikari.HikariDataSource : HikariPool-2 - Starting...
2026-08-10T16:34:25.818Z INFO 1931 --- [ Test worker] tc.postgres:17.7-trixie : Creating container for image: postgres:17.7-trixie
2026-08-10T16:34:25.955Z INFO 1931 --- [ Test worker] tc.postgres:17.7-trixie : Container postgres:17.7-trixie is starting: d1cfb8f30c4b0ab068cab0c0ff07b0be3c53eb435a8d04b2831b1a35fe5eeeee
2026-08-10T16:34:27.280Z INFO 1931 --- [ Test worker] tc.postgres:17.7-trixie : Container postgres:17.7-trixie started in PT1.461819358S
2026-08-10T16:34:27.280Z INFO 1931 --- [ Test worker] tc.postgres:17.7-trixie : Container is started (JDBC URL: jdbc:postgresql://localhost:32898/test?loggerLevel=OFF)
2026-08-10T16:34:27.310Z INFO 1931 --- [ Test worker] com.zaxxer.hikari.pool.HikariPool : HikariPool-2 - Added connection org.testcontainers.jdbc.ConnectionWrapper@59731f03
2026-08-10T16:34:27.311Z INFO 1931 --- [ Test worker] com.zaxxer.hikari.HikariDataSource : HikariPool-2 - Start completed.
2026-08-10T16:34:27.679Z WARN 1931 --- [ Test worker] liquibase.executor : view "tx_journal_v" does not exist, skipping
2026-08-10T16:34:27.802Z WARN 1931 --- [ Test worker] liquibase.executor : trigger "delete_grants_of_subject_tg" for relation "rbac.subject" does not exist, skipping
2026-08-10T16:34:27.803Z WARN 1931 --- [ Test worker] liquibase.executor : trigger "delete_reference_of_subject_tg" for relation "rbac.subject" does not exist, skipping
2026-08-10T16:34:27.823Z WARN 1931 --- [ Test worker] liquibase.executor : trigger "subject_default_organization_tg" for relation "rbac.subject" does not exist, skipping
2026-08-10T16:34:27.854Z WARN 1931 --- [ Test worker] liquibase.executor : function rbac.determinecurrentsubjectorassumedrolesuuids(uuid,text) does not exist, skipping
2026-08-10T16:34:27.864Z WARN 1931 --- [ Test worker] liquibase.executor : procedure base.contextdefined(pg_catalog.varchar,text,pg_catalog.varchar,pg_catalog.varchar) does not exist, skipping
2026-08-10T16:34:27.864Z WARN 1931 --- [ Test worker] liquibase.executor : procedure base.definecontext(pg_catalog.varchar,text,pg_catalog.varchar,text) does not exist, skipping
2026-08-10T16:34:27.874Z WARN 1931 --- [ Test worker] liquibase.executor : view "role_ev" does not exist, skipping
2026-08-10T16:34:27.887Z WARN 1931 --- [ Test worker] liquibase.executor : view "grant_ev" does not exist, skipping
2026-08-10T16:34:27.906Z WARN 1931 --- [ Test worker] liquibase.executor : view "subject_ev" does not exist, skipping
2026-08-10T16:34:27.922Z WARN 1931 --- [ Test worker] liquibase.executor : function rbac.upsert_subject(uuid,pg_catalog.varchar,rbac.subjecttype) does not exist, skipping
2026-08-10T16:34:27.923Z WARN 1931 --- [ Test worker] liquibase.executor : function rbac.upsert_subject(uuid,pg_catalog.varchar,rbac.subjecttype,pg_catalog.bool) does not exist, skipping
2026-08-10T16:34:27.923Z WARN 1931 --- [ Test worker] liquibase.executor : function rbac.upsert_subject(uuid,pg_catalog.varchar,pg_catalog.varchar,rbac.subjecttype) does not exist, skipping
2026-08-10T16:34:27.969Z WARN 1931 --- [ Test worker] liquibase.executor : view "statistics_v" does not exist, skipping
2026-08-10T16:34:28.006Z WARN 1931 --- [ Test worker] liquibase.executor : there is already a transaction in progress
2026-08-10T16:34:28.007Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: initializing table "rbac.global", , , []
2026-08-10T16:34:28.011Z WARN 1931 --- [ Test worker] liquibase.executor : there is already a transaction in progress
2026-08-10T16:34:28.012Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: creating role:rbac.global#global:ADMIN, , , []
2026-08-10T16:34:28.015Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: creating role:rbac.global#global:guest, , , []
2026-08-10T16:34:28.019Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: renaming seed subjects to realm-prefixed format, , , []
2026-08-10T16:34:28.022Z WARN 1931 --- [ Test worker] liquibase.executor : constraint "check_valid_user_subject_name" of relation "subject" does not exist, skipping
2026-08-10T16:34:28.022Z WARN 1931 --- [ Test worker] liquibase.executor : constraint "check_valid_group_subject_name" of relation "subject" does not exist, skipping
2026-08-10T16:34:28.023Z WARN 1931 --- [ Test worker] liquibase.executor : constraint "check_valid_api_key_subject_name" of relation "subject" does not exist, skipping
2026-08-10T16:34:28.030Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: back-filling subject organization from the name prefix, , , []
2026-08-10T16:34:28.039Z WARN 1931 --- [ Test worker] liquibase.executor : constraint "api_key_scope_scope_format" of relation "api_key_scope" does not exist, skipping
2026-08-10T16:34:28.044Z WARN 1931 --- [ Test worker] liquibase.executor : function rbac.create_api_key(uuid,pg_catalog.varchar,pg_catalog.varchar[]) does not exist, skipping
2026-08-10T16:34:28.086Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T16:34:28.134Z WARN 1931 --- [ Test worker] liquibase.executor : constraint "relation_check_debitor_anchor_person" of relation "relation" does not exist, skipping
2026-08-10T16:34:28.134Z WARN 1931 --- [ Test worker] liquibase.executor : function hs_office.relation_check_debitor_anchor_partner() does not exist, skipping
2026-08-10T16:34:28.137Z WARN 1931 --- [ 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:34:28.155Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.relation permissions for pre-exising hs_office.person rows, , , []
2026-08-10T16:34:28.247Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.partner permissions for pre-exising rbac.global rows, , , []
2026-08-10T16:34:28.270Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.partner_details permissions for pre-exising rbac.global rows, , , []
2026-08-10T16:34:28.291Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T16:34:28.340Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.debitor permissions for pre-exising rbac.global rows, , , []
2026-08-10T16:34:28.371Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.sepamandate permissions for pre-exising hs_office.relation rows, , , []
2026-08-10T16:34:28.393Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T16:34:28.420Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.membership permissions for pre-exising rbac.global rows, , , []
2026-08-10T16:34:28.440Z WARN 1931 --- [ Test worker] liquibase.executor : constraint "check_positive_total_shares_count" of relation "coopsharetx" does not exist, skipping
2026-08-10T16:34:28.440Z WARN 1931 --- [ Test worker] liquibase.executor : function hs_office.coopsharestx_check_positive_total() does not exist, skipping
2026-08-10T16:34:28.440Z WARN 1931 --- [ Test worker] liquibase.executor : trigger "positive_total_shares_count_tg" for relation "hs_office.coopsharetx" does not exist, skipping
2026-08-10T16:34:28.452Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.coopsharetx permissions for pre-exising hs_office.membership rows, , , []
2026-08-10T16:34:28.470Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T16:34:28.487Z WARN 1931 --- [ Test worker] liquibase.executor : constraint "coopassetstx_check_positive_total" of relation "coopassettx" does not exist, skipping
2026-08-10T16:34:28.487Z WARN 1931 --- [ Test worker] liquibase.executor : function hs_office.coopassetstx_check_positive_total() does not exist, skipping
2026-08-10T16:34:28.487Z WARN 1931 --- [ Test worker] liquibase.executor : trigger "positive_total_assets_count_tg" for relation "hs_office.coopassettx" does not exist, skipping
2026-08-10T16:34:28.501Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_office.coopassettx permissions for pre-exising hs_office.membership rows, , , []
2026-08-10T16:34:28.520Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T16:34:28.559Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_booking.project permissions for pre-exising hs_office.relation rows, , , []
2026-08-10T16:34:28.603Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_booking.item permissions for pre-exising rbac.global rows, , , []
2026-08-10T16:34:28.603Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: create INSERT INTO hs_booking.item permissions for pre-exising hs_booking.project rows, , , []
2026-08-10T16:34:28.676Z WARN 1931 --- [ Test worker] liquibase.executor : Context defined as: schema-migration, , , []
2026-08-10T16:34:28.685Z WARN 1931 --- [ Test worker] liquibase.executor : view "hs_statistics_v" does not exist, skipping
2026-08-10T16:34:28.694Z WARN 1931 --- [ Test worker] liquibase.executor : view "account_ev" does not exist, skipping
2026-08-10T16:34:28.701Z WARN 1931 --- [ Test worker] liquibase.executor : trigger "assert_account_subject_is_user_tg" for relation "hs_accounts.account" does not exist, skipping
2026-08-10T16:34:28.772Z INFO 1931 --- [ Test worker] o.hibernate.jpa.internal.util.LogHelper : HHH000204: Processing PersistenceUnitInfo [name: default]
2026-08-10T16:34:28.774Z INFO 1931 --- [ Test worker] o.h.c.internal.RegionFactoryInitiator : HHH000026: Second-level cache disabled
2026-08-10T16:34:28.779Z INFO 1931 --- [ 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:34:28.787Z INFO 1931 --- [ Test worker] o.s.o.j.p.SpringPersistenceUnitInfo : No LoadTimeWeaver setup: ignoring JPA class transformer
2026-08-10T16:34:28.821Z WARN 1931 --- [ 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:34:28.822Z WARN 1931 --- [ 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:34:28.827Z WARN 1931 --- [ 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:34:28.829Z WARN 1931 --- [ 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:34:29.025Z INFO 1931 --- [ 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:34:29.025Z INFO 1931 --- [ Test worker] j.LocalContainerEntityManagerFactoryBean : Initialized JPA EntityManagerFactory for persistence unit 'default'
2026-08-10T16:34:29.383Z WARN 1931 --- [ 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:34:29.691Z INFO 1931 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat started on port 34065 (http) with context path '/'
2026-08-10T16:34:29.718Z INFO 1931 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat initialized with port 0 (http)
2026-08-10T16:34:29.719Z INFO 1931 --- [ Test worker] o.apache.catalina.core.StandardService : Starting service [Tomcat]
2026-08-10T16:34:29.719Z INFO 1931 --- [ Test worker] o.apache.catalina.core.StandardEngine : Starting Servlet engine: [Apache Tomcat/10.1.44]
2026-08-10T16:34:29.728Z INFO 1931 --- [ Test worker] o.a.c.c.C.[Tomcat-3].[localhost].[/] : Initializing Spring embedded WebApplicationContext
2026-08-10T16:34:29.728Z INFO 1931 --- [ Test worker] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 35 ms
2026-08-10T16:34:29.733Z INFO 1931 --- [ Test worker] o.s.b.a.e.web.EndpointLinksResolver : Exposing 5 endpoints beneath base path '/actuator'
2026-08-10T16:34:29.756Z INFO 1931 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat started on port 39605 (http) with context path '/'
2026-08-10T16:34:29.773Z INFO 1931 --- [ Test worker] n.h.h.h.o.s.d.DemoDataScenarioTest : Started DemoDataScenarioTest in 4.468 seconds (process running for 40.387)
2026-08-10T16:34:29.779Z INFO 1931 --- [ Test worker] n.h.h.c.ProvisioningApiKeyBootstrap : provisioned the provisioning API-key subject 'hsadminng.provisioning.key' with the global ADMIN role
2026-08-10T16:34:29.796Z INFO 1931 --- [o-auto-3-exec-1] o.a.c.c.C.[Tomcat-2].[localhost].[/] : Initializing Spring DispatcherServlet 'dispatcherServlet'
2026-08-10T16:34:29.796Z INFO 1931 --- [o-auto-3-exec-1] o.s.web.servlet.DispatcherServlet : Initializing Servlet 'dispatcherServlet'
2026-08-10T16:34:29.797Z INFO 1931 --- [o-auto-3-exec-1] o.s.web.servlet.DispatcherServlet : Completed initialization in 1 ms
SCENARIO REPORT: file:///home/tallyman/hs.hsadmin.ng/.git/gittally/worktrees/master-fc613b4dfd67/build/scenario-reports/9999:%20Generate%20Demo%20Dataset.md
DEBUG LOG: file:///home/tallyman/hs.hsadmin.ng/.git/gittally/worktrees/master-fc613b4dfd67/build/scenario-reports/.last-debug-log.md