aabarmin / epam-microservices-training-2022

Apache License 2.0
14 stars 15 forks source link

[help needed] microservice-delivery app starts, then exits immediately #20

Open naXa777 opened 1 year ago

naXa777 commented 1 year ago

Steps

  1. start zipkin

    cd microservices/docker/zipkin
    docker-compose up
  2. start microservice

    cd microservices/microservice-delivery
    mvn clean install
    cd delivery-app
    mvn spring-boot:run

Full Logs

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

2023-10-15 01:01:01.463  INFO 17320 --- [  restartedMain] c.e.t.s.delivery.DeliveryApplication     : Starting DeliveryApplication using Java 11.0.17 on Dell-XPS with PID 17320 (D:\dev\hacktoberfest\epam-microservices-training-2022\microservices\microservice-delivery\delivery-app\target\classes started by naxa in D:\dev\hacktoberfest\epam-microservices-training-2022)
2023-10-15 01:01:01.464  INFO 17320 --- [  restartedMain] c.e.t.s.delivery.DeliveryApplication     : No active profile set, falling back to default profiles: default
2023-10-15 01:01:01.505  INFO 17320 --- [  restartedMain] .e.DevToolsPropertyDefaultsPostProcessor : Devtools property defaults active! Set 'spring.devtools.add-properties' to 'false' to disable
2023-10-15 01:01:02.095  INFO 17320 --- [  restartedMain] .s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2023-10-15 01:01:02.134  INFO 17320 --- [  restartedMain] .s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository scanning in 33 ms. Found 1 JPA repository interfaces.
2023-10-15 01:01:02.421  INFO 17320 --- [  restartedMain] com.zaxxer.hikari.HikariDataSource       : HikariPool-1 - Starting...
2023-10-15 01:01:02.509  INFO 17320 --- [  restartedMain] com.zaxxer.hikari.HikariDataSource       : HikariPool-1 - Start completed.
2023-10-15 01:01:02.540  INFO 17320 --- [  restartedMain] o.hibernate.jpa.internal.util.LogHelper  : HHH000204: Processing PersistenceUnitInfo [name: default]
2023-10-15 01:01:02.573  INFO 17320 --- [  restartedMain] org.hibernate.Version                    : HHH000412: Hibernate ORM core version 5.6.3.Final
2023-10-15 01:01:02.671  INFO 17320 --- [  restartedMain] o.hibernate.annotations.common.Version   : HCANN000001: Hibernate Commons Annotations {5.1.2.Final}
2023-10-15 01:01:02.746  INFO 17320 --- [  restartedMain] org.hibernate.dialect.Dialect            : HHH000400: Using dialect: org.hibernate.dialect.H2Dialect
2023-10-15 01:01:03.164  INFO 17320 --- [  restartedMain] o.h.e.t.j.p.i.JtaPlatformInitiator       : HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform]
2023-10-15 01:01:03.170  INFO 17320 --- [  restartedMain] j.LocalContainerEntityManagerFactoryBean : Initialized JPA EntityManagerFactory for persistence unit 'default'
2023-10-15 01:01:03.711  WARN 17320 --- [  restartedMain] o.s.b.d.a.OptionalLiveReloadServer       : Unable to start LiveReload server
2023-10-15 01:01:04.044  INFO 17320 --- [  restartedMain] c.e.t.s.delivery.DeliveryApplication     : Started DeliveryApplication in 3.326 seconds (JVM running for 4.144)
2023-10-15 01:01:04.057  INFO 17320 --- [ionShutdownHook] j.LocalContainerEntityManagerFactoryBean : Closing JPA EntityManagerFactory for persistence unit 'default'
2023-10-15 01:01:04.058  INFO 17320 --- [ionShutdownHook] .SchemaDropperImpl$DelayedDropActionImpl : HHH000477: Starting delayed evictData of schema as part of SessionFactory shut-down'
2023-10-15 01:01:04.286  WARN 17320 --- [ionShutdownHook] o.s.b.f.support.DisposableBeanAdapter    : Invocation of destroy method failed on bean with name 'inMemoryDatabaseShutdownExecutor': org.h2.jdbc.JdbcSQLNonTransientConnectionException: Database is already closed (to disable automatic closing at VM shutdown, add ";DB_CLOSE_ON_EXIT=FALSE" to the db URL) [90121-200]
2023-10-15 01:01:04.286  INFO 17320 --- [ionShutdownHook] com.zaxxer.hikari.HikariDataSource       : HikariPool-1 - Shutdown initiated...
2023-10-15 01:01:04.292  INFO 17320 --- [ionShutdownHook] com.zaxxer.hikari.HikariDataSource       : HikariPool-1 - Shutdown completed.

Process finished with exit code 0

???

The service works like this in main branch, it used to work like this for a while (I checked commits 27537fdc and bc8a0fd4). So I'm puzzled.

@aabarmin, Is it broken? Or is it expected to work like this? Does it work for you? Am I doing something wrong?

aabarmin commented 1 year ago

Hi @naXa777 let me have a look at it today or tomorrow. Will update you on my findings.

aabarmin commented 1 year ago

Hi @naXa777

I'm afraid the implementation is not completed so that the application does not work as expected.