hapifhir / hapi-fhir-jpaserver-starter

Apache License 2.0
394 stars 1.05k forks source link

Default spring boot fails #743

Open jgsuess opened 2 months ago

jgsuess commented 2 months ago

With current main branch checked out, the server fails to show the UI at http://localhost:8080

Instead, it yields:

Whitelabel Error Page

This application has no explicit mapping for /error, so you are seeing this as a fallback.

Thu Sep 26 11:32:44 AEST 2024
There was an unexpected error (type=Internal Server Error, status=500).
The execution stack is below. This is on Ubuntu 24.04 with maven as follows:
sue005@annapurna-bh:~/git/hapi-fhir-jpaserver-starter$ mvn --version
Apache Maven 3.8.7
Maven home: /usr/share/maven
Java version: 21.0.4, vendor: Ubuntu, runtime: /usr/lib/jvm/java-21-openjdk-amd64
Default locale: en_AU, platform encoding: UTF-8
OS name: "linux", version: "6.8.0-45-generic", arch: "amd64", family: "unix"
sue005@annapurna-bh:~/git/hapi-fhir-jpaserver-starter$ mvn clean && mvn spring-boot:run
[INFO] Scanning for projects...
[WARNING] The project ca.uhn.hapi.fhir:hapi-fhir-jpaserver-starter:war:7.4.0 uses prerequisites which is only intended for maven-plugin projects but not for non maven-plugin projects. For such purposes you should use the maven-enforcer-plugin. See https://maven.apache.org/enforcer/enforcer-rules/requireMavenVersion.html
[INFO] 
[INFO] ------------< ca.uhn.hapi.fhir:hapi-fhir-jpaserver-starter >------------
[INFO] Building HAPI FHIR JPA Server - Starter Project 7.4.0
[INFO] --------------------------------[ war ]---------------------------------
[INFO] 
[INFO] --- maven-clean-plugin:3.1.0:clean (default-clean) @ hapi-fhir-jpaserver-starter ---
[INFO] Deleting /home/sue005/git/hapi-fhir-jpaserver-starter/target
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time:  0.206 s
[INFO] Finished at: 2024-09-26T11:32:15+10:00
[INFO] ------------------------------------------------------------------------
[INFO] Scanning for projects...
[WARNING] The project ca.uhn.hapi.fhir:hapi-fhir-jpaserver-starter:war:7.4.0 uses prerequisites which is only intended for maven-plugin projects but not for non maven-plugin projects. For such purposes you should use the maven-enforcer-plugin. See https://maven.apache.org/enforcer/enforcer-rules/requireMavenVersion.html
[INFO] 
[INFO] ------------< ca.uhn.hapi.fhir:hapi-fhir-jpaserver-starter >------------
[INFO] Building HAPI FHIR JPA Server - Starter Project 7.4.0
[INFO] --------------------------------[ war ]---------------------------------
[INFO] 
[INFO] >>> spring-boot-maven-plugin:3.2.6:run (default-cli) > test-compile @ hapi-fhir-jpaserver-starter >>>
[INFO] 
[INFO] --- maven-enforcer-plugin:3.3.0:enforce (enforce-maven) @ hapi-fhir-jpaserver-starter ---
[INFO] Rule 0: org.apache.maven.enforcer.rules.version.RequireMavenVersion passed
[INFO] Rule 1: org.apache.maven.enforcer.rules.version.RequireJavaVersion passed
[INFO] 
[INFO] --- maven-resources-plugin:3.3.0:resources (default-resources) @ hapi-fhir-jpaserver-starter ---
[INFO] Copying 4 resources
[INFO] 
[INFO] --- maven-compiler-plugin:3.13.0:compile (default-compile) @ hapi-fhir-jpaserver-starter ---
[INFO] Recompiling the module because of changed source code.
[INFO] Compiling 58 source files with javac [forked debug release 17] to target/classes
[WARNING] Unable to autodetect 'javac' path, using 'javac' from the environment.

...
  Did you mean '@Override public void remove(Supplier<Object> theSupplier) {'?
[INFO] 
[INFO] --- maven-resources-plugin:3.3.0:testResources (default-testResources) @ hapi-fhir-jpaserver-starter ---
[INFO] Copying 18 resources
[INFO] 
[INFO] --- maven-compiler-plugin:3.13.0:testCompile (default-testCompile) @ hapi-fhir-jpaserver-starter ---
[INFO] Recompiling the module because of changed dependency.
[INFO] Compiling 20 source files with javac [forked debug release 17] to target/test-classes
[WARNING] Unable to autodetect 'javac' path, using 'javac' from the environment.
[INFO] 
[INFO] <<< spring-boot-maven-plugin:3.2.6:run (default-cli) < test-compile @ hapi-fhir-jpaserver-starter <<<
[INFO] 
[INFO] 
[INFO] --- spring-boot-maven-plugin:3.2.6:run (default-cli) @ hapi-fhir-jpaserver-starter ---
[INFO] Attaching agents: []

  .   ____          _            __ _ _
 /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
 \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
  '  |____| .__|_| |_|_| |_\__, | / / / /
 =========|_|==============|___/=/_/_/_/
 :: Spring Boot ::                (v3.2.6)

2024-09-26T11:32:22.182+10:00  INFO 184355 --- [           main] ca.uhn.fhir.jpa.starter.Application      : Starting Application using Java 21.0.4 with PID 184355 (/home/sue005/git/hapi-fhir-jpaserver-starter/target/classes started by sue005 in /home/sue005/git/hapi-fhir-jpaserver-starter)
2024-09-26T11:32:22.183+10:00  INFO 184355 --- [           main] ca.uhn.fhir.jpa.starter.Application      : No active profile set, falling back to 1 default profile: "default"
2024-09-26T11:32:22.618+10:00  INFO 184355 --- [           main] .s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2024-09-26T11:32:22.695+10:00  INFO 184355 --- [           main] .s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository scanning in 73 ms. Found 53 JPA repository interfaces.
2024-09-26T11:32:23.422+10:00  WARN 184355 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'ca.uhn.fhir.jpa.config.BeanPostProcessorConfig' of type [ca.uhn.fhir.jpa.config.BeanPostProcessorConfig$$SpringCGLIB$$0] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying). The currently created BeanPostProcessor [persistenceExceptionTranslationPostProcessor] is declared through a non-static factory method on that class; consider declaring it as static instead.
2024-09-26T11:32:23.589+10:00  INFO 184355 --- [           main] o.s.b.w.embedded.tomcat.TomcatWebServer  : Tomcat initialized with port 8080 (http)
2024-09-26T11:32:23.596+10:00  INFO 184355 --- [           main] o.apache.catalina.core.StandardService   : Starting service [Tomcat]
2024-09-26T11:32:23.596+10:00  INFO 184355 --- [           main] o.apache.catalina.core.StandardEngine    : Starting Servlet engine: [Apache Tomcat/10.1.24]
2024-09-26T11:32:23.631+10:00  INFO 184355 --- [           main] o.a.c.c.C.[Tomcat].[localhost].[/]       : Initializing Spring embedded WebApplicationContext
2024-09-26T11:32:23.631+10:00  INFO 184355 --- [           main] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 1417 ms
2024-09-26T11:32:23.673+10:00  INFO 184355 --- [           main] ca.uhn.fhir.util.VersionUtil             : HAPI FHIR version 7.4.0 - Rev 71e9af61cf
2024-09-26T11:32:23.675+10:00  INFO 184355 --- [           main] ca.uhn.fhir.context.FhirContext          : Creating new FHIR context for FHIR version [R4]
2024-09-26T11:32:23.688+10:00  INFO 184355 --- [           main] c.u.f.j.s.common.FhirServerConfigCommon  : Server configured to allow contains searches
2024-09-26T11:32:23.688+10:00  INFO 184355 --- [           main] c.u.f.j.s.common.FhirServerConfigCommon  : Server configured to deny multiple deletes
2024-09-26T11:32:23.688+10:00  INFO 184355 --- [           main] c.u.f.j.s.common.FhirServerConfigCommon  : Server configured to deny external references
2024-09-26T11:32:23.688+10:00  INFO 184355 --- [           main] c.u.f.j.s.common.FhirServerConfigCommon  : Server configured to enable DAO scheduling
2024-09-26T11:32:23.688+10:00  INFO 184355 --- [           main] c.u.f.j.s.common.FhirServerConfigCommon  : Server configured to disable delete expunges
2024-09-26T11:32:23.689+10:00  INFO 184355 --- [           main] c.u.f.j.s.common.FhirServerConfigCommon  : Server configured to enable expunges
2024-09-26T11:32:23.689+10:00  INFO 184355 --- [           main] c.u.f.j.s.common.FhirServerConfigCommon  : Server configured to allow overriding default search params
2024-09-26T11:32:23.689+10:00  INFO 184355 --- [           main] c.u.f.j.s.common.FhirServerConfigCommon  : Server configured to disable auto-creating placeholder references
2024-09-26T11:32:23.689+10:00  INFO 184355 --- [           main] c.u.f.j.s.common.FhirServerConfigCommon  : Server configured to auto-version references at paths []
2024-09-26T11:32:23.700+10:00  INFO 184355 --- [           main] c.u.f.j.s.common.FhirServerConfigCommon  : Server configured to have a maximum fetch size of 'unlimited'
2024-09-26T11:32:23.701+10:00  INFO 184355 --- [           main] c.u.f.j.s.common.FhirServerConfigCommon  : Server configured to cache search results for 60000 milliseconds
2024-09-26T11:32:23.701+10:00  INFO 184355 --- [           main] c.u.f.j.s.common.FhirServerConfigCommon  : Server configured to use 'ALPHANUMERIC' Client ID Strategy
2024-09-26T11:32:23.732+10:00  INFO 184355 --- [           main] o.hibernate.jpa.internal.util.LogHelper  : HHH000204: Processing PersistenceUnitInfo [name: HAPI_PU]
2024-09-26T11:32:23.783+10:00  INFO 184355 --- [           main] org.hibernate.Version                    : HHH000412: Hibernate ORM core version 6.4.1.Final
2024-09-26T11:32:23.788+10:00  INFO 184355 --- [           main] .f.j.l.FilteringSqlLoggerImplContributor : Adding service: SqlStatementFilteringLogger
2024-09-26T11:32:23.803+10:00  INFO 184355 --- [           main] o.h.c.internal.RegionFactoryInitiator    : HHH000026: Second-level cache disabled
2024-09-26T11:32:23.839+10:00  INFO 184355 --- [           main] o.h.e.boot.internal.EnversServiceImpl    : Envers integration enabled? : true
2024-09-26T11:32:23.922+10:00  INFO 184355 --- [           main] o.s.o.j.p.SpringPersistenceUnitInfo      : No LoadTimeWeaver setup: ignoring JPA class transformer
2024-09-26T11:32:23.936+10:00  INFO 184355 --- [           main] com.zaxxer.hikari.HikariDataSource       : HikariPool-1 - Starting...
2024-09-26T11:32:24.160+10:00  INFO 184355 --- [           main] com.zaxxer.hikari.pool.HikariPool        : HikariPool-1 - Added connection conn0: url=jdbc:h2:mem:test_mem user=SA
2024-09-26T11:32:24.161+10:00  INFO 184355 --- [           main] com.zaxxer.hikari.HikariDataSource       : HikariPool-1 - Start completed.
2024-09-26T11:32:24.458+10:00  INFO 184355 --- [           main] o.h.e.c.i.m.AuditMetadataGenerator       : Adding properties for entity: ca.uhn.fhir.jpa.entity.MdmLink
2024-09-26T11:32:24.478+10:00  INFO 184355 --- [           main] b.i.HibernateSearchPreIntegrationService : HSEARCH000034: Hibernate Search version 7.0.0.Final
2024-09-26T11:32:25.248+10:00  INFO 184355 --- [           main] o.h.e.t.j.p.i.JtaPlatformInitiator       : HHH000489: No JTA platform available (set 'hibernate.transaction.jta.platform' to enable JTA platform integration)
2024-09-26T11:32:25.339+10:00  INFO 184355 --- [           main] irLocalContainerEntityManagerFactoryBean : Initialized JPA EntityManagerFactory for persistence unit 'HAPI_PU'
2024-09-26T11:32:25.676+10:00  INFO 184355 --- [           main] .u.f.c.s.DefaultProfileValidationSupport : Loading structure definitions from classpath: /org/hl7/fhir/r4/model/profile/profiles-resources.xml
2024-09-26T11:32:25.686+10:00  INFO 184355 --- [           main] ca.uhn.fhir.util.XmlUtil                 : FHIR XML procesing will use StAX implementation 'Woodstox' version '6.4.0'
2024-09-26T11:32:26.159+10:00  INFO 184355 --- [           main] .u.f.c.s.DefaultProfileValidationSupport : Loading structure definitions from classpath: /org/hl7/fhir/r4/model/profile/profiles-types.xml
2024-09-26T11:32:26.187+10:00  INFO 184355 --- [           main] .u.f.c.s.DefaultProfileValidationSupport : Loading structure definitions from classpath: /org/hl7/fhir/r4/model/profile/profiles-others.xml
2024-09-26T11:32:26.303+10:00  INFO 184355 --- [           main] .u.f.c.s.DefaultProfileValidationSupport : Loading structure definitions from classpath: /org/hl7/fhir/r4/model/extension/extension-definitions.xml
2024-09-26T11:32:26.642+10:00  INFO 184355 --- [           main] o.s.d.j.r.query.QueryEnhancerFactory     : Hibernate is in classpath; If applicable, HQL parser will be used.
2024-09-26T11:32:27.535+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Creating Local Scheduler
2024-09-26T11:32:27.550+10:00  INFO 184355 --- [           main] org.quartz.impl.StdSchedulerFactory      : Using default implementation for ThreadExecutor
2024-09-26T11:32:27.556+10:00  INFO 184355 --- [           main] org.quartz.core.SchedulerSignalerImpl    : Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
2024-09-26T11:32:27.556+10:00  INFO 184355 --- [           main] org.quartz.core.QuartzScheduler          : Quartz Scheduler v.2.3.2 created.
2024-09-26T11:32:27.556+10:00  INFO 184355 --- [           main] org.quartz.simpl.RAMJobStore             : RAMJobStore initialized.
2024-09-26T11:32:27.556+10:00  INFO 184355 --- [           main] org.quartz.core.QuartzScheduler          : Scheduler meta-data: Quartz Scheduler (v2.3.2) 'hapi-fhir-jpa-scheduler' with instanceId 'NON_CLUSTERED'
  Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
  NOT STARTED.
  Currently in standby mode.
  Number of jobs executed: 0
  Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 4 threads.
  Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered.

2024-09-26T11:32:27.556+10:00  INFO 184355 --- [           main] org.quartz.impl.StdSchedulerFactory      : Quartz scheduler 'hapi-fhir-jpa-scheduler' initialized from an externally provided properties instance.
2024-09-26T11:32:27.556+10:00  INFO 184355 --- [           main] org.quartz.impl.StdSchedulerFactory      : Quartz scheduler version: 2.3.2
2024-09-26T11:32:27.556+10:00  INFO 184355 --- [           main] org.quartz.core.QuartzScheduler          : JobFactory set to: ca.uhn.fhir.jpa.sched.AutowiringSpringBeanJobFactory@f51c595
2024-09-26T11:32:27.556+10:00  INFO 184355 --- [           main] org.quartz.core.QuartzScheduler          : Scheduler hapi-fhir-jpa-scheduler_$_NON_CLUSTERED paused.
2024-09-26T11:32:27.556+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Creating Clustered Scheduler
2024-09-26T11:32:27.557+10:00  INFO 184355 --- [           main] org.quartz.impl.StdSchedulerFactory      : Using default implementation for ThreadExecutor
2024-09-26T11:32:27.557+10:00  INFO 184355 --- [           main] org.quartz.core.SchedulerSignalerImpl    : Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
2024-09-26T11:32:27.557+10:00  INFO 184355 --- [           main] org.quartz.core.QuartzScheduler          : Quartz Scheduler v.2.3.2 created.
2024-09-26T11:32:27.557+10:00  INFO 184355 --- [           main] org.quartz.simpl.RAMJobStore             : RAMJobStore initialized.
2024-09-26T11:32:27.557+10:00  INFO 184355 --- [           main] org.quartz.core.QuartzScheduler          : Scheduler meta-data: Quartz Scheduler (v2.3.2) 'hapi-fhir-jpa-scheduler' with instanceId 'NON_CLUSTERED'
  Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
  NOT STARTED.
  Currently in standby mode.
  Number of jobs executed: 0
  Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 4 threads.
  Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered.

2024-09-26T11:32:27.557+10:00  INFO 184355 --- [           main] org.quartz.impl.StdSchedulerFactory      : Quartz scheduler 'hapi-fhir-jpa-scheduler' initialized from an externally provided properties instance.
2024-09-26T11:32:27.557+10:00  INFO 184355 --- [           main] org.quartz.impl.StdSchedulerFactory      : Quartz scheduler version: 2.3.2
2024-09-26T11:32:27.557+10:00  INFO 184355 --- [           main] org.quartz.core.QuartzScheduler          : JobFactory set to: ca.uhn.fhir.jpa.sched.AutowiringSpringBeanJobFactory@f51c595
2024-09-26T11:32:27.557+10:00  INFO 184355 --- [           main] org.quartz.core.QuartzScheduler          : Scheduler hapi-fhir-jpa-scheduler_$_NON_CLUSTERED paused.
2024-09-26T11:32:27.748+10:00  INFO 184355 --- [           main] ca.uhn.fhir.context.FhirContext          : Creating new FHIR context for FHIR version [R4]
2024-09-26T11:32:27.871+10:00  INFO 184355 --- [           main] c.u.f.j.starter.common.StarterJpaConfig  : CORS is enabled on this server
2024-09-26T11:32:27.872+10:00  INFO 184355 --- [           main] c.u.f.j.starter.common.StarterJpaConfig  : CORS allows the following origins: *
2024-09-26T11:32:31.502+10:00  INFO 184355 --- [           main] ca.uhn.fhir.context.FhirContext          : Creating new FHIR context for FHIR version [R4]
2024-09-26T11:32:31.791+10:00  WARN 184355 --- [           main] 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
2024-09-26T11:32:31.967+10:00  INFO 184355 --- [           main] .s.i.SubscriptionSubmitInterceptorLoader : Subscriptions are disabled on this server.  Subscriptions will not be activated and incoming resources will not be matched against subscriptions.
2024-09-26T11:32:32.112+10:00  INFO 184355 --- [           main] c.u.f.j.s.registry.JpaSearchParamCache   : Have 0 unique search params
2024-09-26T11:32:32.347+10:00  INFO 184355 --- [           main] org.quartz.impl.StdSchedulerFactory      : Using default implementation for ThreadExecutor
2024-09-26T11:32:32.348+10:00  INFO 184355 --- [           main] org.quartz.core.SchedulerSignalerImpl    : Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
2024-09-26T11:32:32.348+10:00  INFO 184355 --- [           main] org.quartz.core.QuartzScheduler          : Quartz Scheduler v.2.3.2 created.
2024-09-26T11:32:32.348+10:00  INFO 184355 --- [           main] org.quartz.simpl.RAMJobStore             : RAMJobStore initialized.
2024-09-26T11:32:32.348+10:00  INFO 184355 --- [           main] org.quartz.core.QuartzScheduler          : Scheduler meta-data: Quartz Scheduler (v2.3.2) 'quartzScheduler' with instanceId 'NON_CLUSTERED'
  Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
  NOT STARTED.
  Currently in standby mode.
  Number of jobs executed: 0
  Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 10 threads.
  Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered.

2024-09-26T11:32:32.348+10:00  INFO 184355 --- [           main] org.quartz.impl.StdSchedulerFactory      : Quartz scheduler 'quartzScheduler' initialized from an externally provided properties instance.
2024-09-26T11:32:32.348+10:00  INFO 184355 --- [           main] org.quartz.impl.StdSchedulerFactory      : Quartz scheduler version: 2.3.2
2024-09-26T11:32:32.348+10:00  INFO 184355 --- [           main] org.quartz.core.QuartzScheduler          : JobFactory set to: org.springframework.scheduling.quartz.SpringBeanJobFactory@66413a14
2024-09-26T11:32:32.366+10:00  INFO 184355 --- [           main] o.s.b.a.e.web.EndpointLinksResolver      : Exposing 1 endpoint(s) beneath base path '/actuator'
2024-09-26T11:32:32.448+10:00  INFO 184355 --- [           main] ca.uhn.fhir.rest.server.RestfulServer    : Initializing HAPI FHIR restful server running in R4 mode
2024-09-26T11:32:32.450+10:00  INFO 184355 --- [           main] ca.uhn.fhir.rest.server.RestfulServer    : Added 147 resource provider(s). Total 147
2024-09-26T11:32:32.625+10:00  INFO 184355 --- [           main] ca.uhn.fhir.rest.server.RestfulServer    : Added 4 plain provider(s). Total 4
2024-09-26T11:32:32.628+10:00  INFO 184355 --- [           main] ca.uhn.fhir.rest.server.RestfulServer    : Removing RESTful methods for: class ca.uhn.fhir.jpa.provider.JpaCapabilityStatementProvider
2024-09-26T11:32:32.628+10:00  INFO 184355 --- [           main] ca.uhn.fhir.rest.server.RestfulServer    : OperationDefinition binding of ca.uhn.fhir.rest.server.method.ReadMethodBinding@705bd68c was removed
2024-09-26T11:32:32.628+10:00  INFO 184355 --- [           main] ca.uhn.fhir.rest.server.RestfulServer    : OperationDefinition binding of ca.uhn.fhir.rest.server.method.ReadMethodBinding@41d71bec was removed
2024-09-26T11:32:32.640+10:00  INFO 184355 --- [           main] ca.uhn.fhir.rest.server.RestfulServer    : A FHIR has been lit on this server
2024-09-26T11:32:32.640+10:00  INFO 184355 --- [           main] o.s.b.w.embedded.tomcat.TomcatWebServer  : Tomcat started on port 8080 (http) with context path ''
2024-09-26T11:32:32.642+10:00  INFO 184355 --- [           main] o.s.s.quartz.SchedulerFactoryBean        : Starting Quartz Scheduler now
2024-09-26T11:32:32.642+10:00  INFO 184355 --- [           main] org.quartz.core.QuartzScheduler          : Scheduler quartzScheduler_$_NON_CLUSTERED started.
2024-09-26T11:32:32.643+10:00  INFO 184355 --- [           main] ca.uhn.fhir.log.batch_troubleshooting    : Registering Batch2 Job Definition: BULK_IMPORT_PULL / 1
2024-09-26T11:32:32.644+10:00  INFO 184355 --- [           main] ca.uhn.fhir.log.batch_troubleshooting    : Registering Batch2 Job Definition: REINDEX / 1
2024-09-26T11:32:32.644+10:00  INFO 184355 --- [           main] ca.uhn.fhir.log.batch_troubleshooting    : Registering Batch2 Job Definition: DELETE_EXPUNGE / 1
2024-09-26T11:32:32.644+10:00  INFO 184355 --- [           main] ca.uhn.fhir.log.batch_troubleshooting    : Registering Batch2 Job Definition: BULK_EXPORT / 1
2024-09-26T11:32:32.644+10:00  INFO 184355 --- [           main] ca.uhn.fhir.log.batch_troubleshooting    : Registering Batch2 Job Definition: BULK_EXPORT / 2
2024-09-26T11:32:32.644+10:00  INFO 184355 --- [           main] ca.uhn.fhir.log.batch_troubleshooting    : Registering Batch2 Job Definition: termCodeSystemVersionDeleteJob / 1
2024-09-26T11:32:32.644+10:00  INFO 184355 --- [           main] ca.uhn.fhir.log.batch_troubleshooting    : Registering Batch2 Job Definition: termCodeSystemDeleteJob / 1
2024-09-26T11:32:32.644+10:00  INFO 184355 --- [           main] ca.uhn.fhir.log.batch_troubleshooting    : Registering Batch2 Job Definition: bulkImportJob / 1
2024-09-26T11:32:32.644+10:00  INFO 184355 --- [           main] .j.s.m.m.s.MatchingQueueSubscriberLoader : Subscription Matching Subscriber subscribed to Matching Channel ca.uhn.fhir.jpa.subscription.channel.subscription.BroadcastingSubscribableChannelWrapper with name subscription-matching
2024-09-26T11:32:32.652+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Scheduling 15 jobs in application
2024-09-26T11:32:32.652+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Scheduling local job ca.uhn.fhir.jpa.term.TermDeferredStorageSvcImpl$Job with interval 5000ms
2024-09-26T11:32:32.654+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Scheduling local job ca.uhn.fhir.jpa.cache.ResourceChangeListenerCacheRefresherImpl with interval 00:00:10.000
2024-09-26T11:32:32.655+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Scheduling clustered job ca.uhn.fhir.jpa.search.reindex.ResourceReindexingSvcImpl with interval 00:00:10.000
2024-09-26T11:32:32.655+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Scheduling clustered job ca.uhn.fhir.jpa.bulk.export.svc.BulkDataExportJobSchedulingHelperImpl$PurgeExpiredFilesJob with interval 01:00:00
2024-09-26T11:32:32.655+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Scheduling local job ca.uhn.fhir.jpa.bulk.imprt.svc.BulkDataImportSvcImpl$ActivationJob with interval 00:00:10.000
2024-09-26T11:32:32.655+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Scheduling clustered job ca.uhn.fhir.jpa.search.warm.CacheWarmingSvcImpl with interval 00:00:10.000
2024-09-26T11:32:32.655+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Scheduling clustered job ca.uhn.fhir.jpa.term.TermReadSvcImpl with interval 00:10:00
2024-09-26T11:32:32.655+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Scheduling local job ca.uhn.fhir.jpa.term.TermReindexingSvcImpl with interval 00:01:00.000
2024-09-26T11:32:32.656+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Scheduling clustered job ca.uhn.fhir.jpa.search.SearchUrlJobMaintenanceSvcImpl$SearchUrlMaintenanceJob with interval 00:10:00
2024-09-26T11:32:32.656+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Scheduling clustered job ca.uhn.fhir.jpa.search.StaleSearchDeletingSvcImpl with interval 00:01:00.000
2024-09-26T11:32:32.656+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Scheduling local job ca.uhn.fhir.jpa.util.ResourceCountCache with interval 00:10:00
2024-09-26T11:32:32.656+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Scheduling local job ca.uhn.fhir.jpa.subscription.triggering.SubscriptionTriggeringSvcImpl with interval 5000ms
2024-09-26T11:32:32.656+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Scheduling clustered job ca.uhn.fhir.jpa.subscription.async.AsyncResourceModifiedProcessingSchedulerSvc with interval 5000ms
2024-09-26T11:32:32.656+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Scheduling clustered job ca.uhn.fhir.batch2.coordinator.ReductionStepExecutorServiceImpl$ReductionStepExecutorScheduledJob with interval 00:00:10.000
2024-09-26T11:32:32.656+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Scheduling clustered job ca.uhn.fhir.batch2.maintenance.JobMaintenanceServiceImpl$JobMaintenanceScheduledJob with interval 00:01:00.000
2024-09-26T11:32:32.656+10:00  INFO 184355 --- [           main] c.u.f.j.sched.BaseSchedulerServiceImpl   : Starting task schedulers for context application
2024-09-26T11:32:32.657+10:00  INFO 184355 --- [           main] c.uhn.fhir.jpa.sched.BaseHapiScheduler   : Starting scheduler hapi-fhir-jpa-scheduler-local
2024-09-26T11:32:32.657+10:00  INFO 184355 --- [           main] org.quartz.core.QuartzScheduler          : Scheduler hapi-fhir-jpa-scheduler_$_NON_CLUSTERED started.
2024-09-26T11:32:32.657+10:00  INFO 184355 --- [           main] c.uhn.fhir.jpa.sched.BaseHapiScheduler   : Starting scheduler hapi-fhir-jpa-scheduler-clustered
2024-09-26T11:32:32.657+10:00  INFO 184355 --- [           main] org.quartz.core.QuartzScheduler          : Scheduler hapi-fhir-jpa-scheduler_$_NON_CLUSTERED started.
2024-09-26T11:32:32.659+10:00  INFO 184355 --- [           main] ca.uhn.fhir.jpa.starter.Application      : Started Application in 10.675 seconds (process running for 10.929)
2024-09-26T11:32:32.671+10:00  INFO 184355 --- [ler-clustered-1] .s.BulkDataExportJobSchedulingHelperImpl : Finished bulk export job deletion with nothing to do
2024-09-26T11:32:43.497+10:00  INFO 184355 --- [nio-8080-exec-1] o.a.c.c.C.[Tomcat].[localhost].[/]       : Initializing Spring DispatcherServlet 'dispatcherServlet'
2024-09-26T11:32:43.497+10:00  INFO 184355 --- [nio-8080-exec-1] o.s.web.servlet.DispatcherServlet        : Initializing Servlet 'dispatcherServlet'
2024-09-26T11:32:43.500+10:00  INFO 184355 --- [nio-8080-exec-1] o.s.web.servlet.DispatcherServlet        : Completed initialization in 3 ms
2024-09-26T11:32:43.534+10:00  INFO 184355 --- [nio-8080-exec-1] ca.uhn.fhir.context.FhirContext          : Creating new FHIR context for FHIR version [R4]
2024-09-26T11:32:44.134+10:00  INFO 184355 --- [nio-8080-exec-2] fhirtest.access                          : Path[/fhir] Source[] Operation[metadata  ] UA[HAPI-FHIR/7.4.0 (FHIR Client; FHIR 4.0.1/R4; apache)] Params[] ResponseEncoding[JSON] Operation[metadata  ] UA[HAPI-FHIR/7.4.0 (FHIR Client; FHIR 4.0.1/R4; apache)] Params[] ResponseEncoding[JSON]
2024-09-26T11:32:44.260+10:00  INFO 184355 --- [nio-8080-exec-1] ca.uhn.fhir.context.FhirContext          : Creating new FHIR context for FHIR version [R4]
2024-09-26T11:32:44.373+10:00 ERROR 184355 --- [nio-8080-exec-1] org.thymeleaf.TemplateEngine             : [THYMELEAF][http-nio-8080-exec-1] Exception processing template "home": An error happened during template parsing (template: "ServletContext resource [/WEB-INF/templates/home.html]")

org.thymeleaf.exceptions.TemplateInputException: An error happened during template parsing (template: "ServletContext resource [/WEB-INF/templates/home.html]")
    at org.thymeleaf.templateparser.markup.AbstractMarkupTemplateParser.parse(AbstractMarkupTemplateParser.java:235)
    at org.thymeleaf.templateparser.markup.AbstractMarkupTemplateParser.parseStandalone(AbstractMarkupTemplateParser.java:100)
    at org.thymeleaf.engine.TemplateManager.parseAndProcess(TemplateManager.java:649)
    at org.thymeleaf.TemplateEngine.process(TemplateEngine.java:1103)
    at org.thymeleaf.TemplateEngine.process(TemplateEngine.java:1077)
    at org.thymeleaf.spring6.view.ThymeleafView.renderFragment(ThymeleafView.java:372)
    at org.thymeleaf.spring6.view.ThymeleafView.render(ThymeleafView.java:192)
    at org.springframework.web.servlet.DispatcherServlet.render(DispatcherServlet.java:1431)
    at org.springframework.web.servlet.DispatcherServlet.processDispatchResult(DispatcherServlet.java:1167)
    at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:1106)
    at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:979)
    at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:1014)
    at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:903)
    at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:527)
    at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:885)
    at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:614)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:195)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:140)
    at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:51)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:164)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:140)
    at org.springframework.web.filter.ServerHttpObservationFilter.doFilterInternal(ServerHttpObservationFilter.java:109)
    at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:116)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:164)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:140)
    at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:201)
    at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:116)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:164)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:140)
    at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:167)
    at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:90)
    at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:482)
    at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:115)
    at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:93)
    at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:74)
    at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:344)
    at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:389)
    at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:63)
    at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:896)
    at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1741)
    at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:52)
    at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1190)
    at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
    at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:63)
    at java.base/java.lang.Thread.run(Thread.java:1583)
Caused by: java.io.FileNotFoundException: Could not open ServletContext resource [/WEB-INF/templates/home.html]
    at org.springframework.web.context.support.ServletContextResource.getInputStream(ServletContextResource.java:165)
    at org.thymeleaf.spring6.templateresource.SpringResourceTemplateResource.reader(SpringResourceTemplateResource.java:103)
    at org.thymeleaf.templateparser.markup.AbstractMarkupTemplateParser.parse(AbstractMarkupTemplateParser.java:223)
    ... 44 common frames omitted

2024-09-26T11:32:44.376+10:00 ERROR 184355 --- [nio-8080-exec-1] o.a.c.c.C.[.[.[/].[dispatcherServlet]    : Servlet.service() for servlet [dispatcherServlet] in context with path [] threw exception [Request processing failed: org.thymeleaf.exceptions.TemplateInputException: An error happened during template parsing (template: "ServletContext resource [/WEB-INF/templates/home.html]")] with root cause

java.io.FileNotFoundException: Could not open ServletContext resource [/WEB-INF/templates/home.html]
    at org.springframework.web.context.support.ServletContextResource.getInputStream(ServletContextResource.java:165)
    at org.thymeleaf.spring6.templateresource.SpringResourceTemplateResource.reader(SpringResourceTemplateResource.java:103)
    at org.thymeleaf.templateparser.markup.AbstractMarkupTemplateParser.parse(AbstractMarkupTemplateParser.java:223)
    at org.thymeleaf.templateparser.markup.AbstractMarkupTemplateParser.parseStandalone(AbstractMarkupTemplateParser.java:100)
    at org.thymeleaf.engine.TemplateManager.parseAndProcess(TemplateManager.java:649)
    at org.thymeleaf.TemplateEngine.process(TemplateEngine.java:1103)
    at org.thymeleaf.TemplateEngine.process(TemplateEngine.java:1077)
    at org.thymeleaf.spring6.view.ThymeleafView.renderFragment(ThymeleafView.java:372)
    at org.thymeleaf.spring6.view.ThymeleafView.render(ThymeleafView.java:192)
    at org.springframework.web.servlet.DispatcherServlet.render(DispatcherServlet.java:1431)
    at org.springframework.web.servlet.DispatcherServlet.processDispatchResult(DispatcherServlet.java:1167)
    at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:1106)
    at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:979)
    at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:1014)
    at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:903)
    at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:527)
    at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:885)
    at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:614)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:195)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:140)
    at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:51)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:164)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:140)
    at org.springframework.web.filter.ServerHttpObservationFilter.doFilterInternal(ServerHttpObservationFilter.java:109)
    at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:116)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:164)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:140)
    at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:201)
    at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:116)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:164)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:140)
    at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:167)
    at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:90)
    at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:482)
    at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:115)
    at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:93)
    at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:74)
    at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:344)
    at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:389)
    at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:63)
    at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:896)
    at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1741)
    at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:52)
    at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1190)
    at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
    at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:63)
    at java.base/java.lang.Thread.run(Thread.java:1583)

sayeedajmal commented 2 months ago

it work while running the jar.. so, mvn clean install and for running java -jar /target/ROOT.war

jkiddo commented 1 month ago

@jgsuess - do the step here: https://github.com/hapifhir/hapi-fhir-jpaserver-starter?tab=readme-ov-file#using-spring-boot-1 if you by "fail" mean that the maven overlay fails to load.