./halo/logs/spring.log
2022-04-04 15:51:04.967 INFO 7 --- [main] run.halo.app.Application : Starting Application v1.5.1 using Java 11.0.11 on 30041f06b4f7 with PID 7 (/application/BOOT-INF/classes started by root in /application)
2022-04-04 15:51:04.971 INFO 7 --- [main] run.halo.app.Application : No active profile set, falling back to 1 default profile: "default"
2022-04-04 15:51:06.242 INFO 7 --- [main] .s.d.r.c.RepositoryConfigurationDelegate : Multiple Spring Data modules found, entering strict repository configuration mode!
2022-04-04 15:51:06.243 INFO 7 --- [main] .s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2022-04-04 15:51:06.630 INFO 7 --- [main] .s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository scanning in 374 ms. Found 24 JPA repository interfaces.
2022-04-04 15:51:07.766 INFO 7 --- [main] org.eclipse.jetty.util.log : Logging initialized @3991ms to org.eclipse.jetty.util.log.Slf4jLog
2022-04-04 15:51:08.008 INFO 7 --- [main] o.s.b.w.e.j.JettyServletWebServerFactory : Server initialized with port: 8090
2022-04-04 15:51:08.018 INFO 7 --- [main] org.eclipse.jetty.server.Server : jetty-9.4.45.v20220203; built: 2022-02-03T09:14:34.105Z; git: 4a0c91c0be53805e3fcffdcdcc9587d5301863db; jvm 11.0.11+9
2022-04-04 15:51:08.076 INFO 7 --- [main] o.e.j.s.h.ContextHandler.application : Initializing Spring embedded WebApplicationContext
2022-04-04 15:51:08.077 INFO 7 --- [main] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 3033 ms
2022-04-04 15:51:08.704 INFO 7 --- [main] run.halo.app.config.HaloConfiguration : Halo cache store load impl : [class run.halo.app.cache.InMemoryCacheStore]
2022-04-04 15:51:09.440 INFO 7 --- [main] o.hibernate.jpa.internal.util.LogHelper : HHH000204: Processing PersistenceUnitInfo [name: default]
2022-04-04 15:51:09.500 INFO 7 --- [main] org.hibernate.Version : HHH000412: Hibernate ORM core version 5.4.33
2022-04-04 15:51:09.546 INFO 7 --- [main] o.hibernate.annotations.common.Version : HCANN000001: Hibernate Commons Annotations {5.1.2.Final}
2022-04-04 15:51:09.672 INFO 7 --- [main] com.zaxxer.hikari.HikariDataSource : HikariPool-1 - Starting...
2022-04-04 15:51:09.941 INFO 7 --- [main] com.zaxxer.hikari.HikariDataSource : HikariPool-1 - Start completed.
2022-04-04 15:51:09.983 INFO 7 --- [main] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.hibernate.dialect.H2Dialect
2022-04-04 15:51:11.122 INFO 7 --- [main] o.h.e.t.j.p.i.JtaPlatformInitiator : HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform]
2022-04-04 15:51:11.132 INFO 7 --- [main] j.LocalContainerEntityManagerFactoryBean : Initialized JPA EntityManagerFactory for persistence unit 'default'
2022-04-04 15:51:11.832 INFO 7 --- [main] org.eclipse.jetty.server.session : DefaultSessionIdManager workerName=node0
2022-04-04 15:51:11.832 INFO 7 --- [main] org.eclipse.jetty.server.session : No SessionScavenger set, using defaults
2022-04-04 15:51:11.833 INFO 7 --- [main] org.eclipse.jetty.server.session : node0 Scavenging every 600000ms
2022-04-04 15:51:11.845 INFO 7 --- [main] o.e.jetty.server.handler.ContextHandler : Started o.s.b.w.e.j.JettyEmbeddedWebAppContext@25c3a441{application,/,[file:///tmp/jetty-docbase.8090.7177670233149472716/, jar:file:/application/BOOT-INF/lib/springfox-swagger-ui-3.0.0.jar!/META-INF/resources],AVAILABLE}
2022-04-04 15:51:11.845 INFO 7 --- [main] org.eclipse.jetty.server.Server : Started @8072ms
2022-04-04 15:51:12.544 INFO 7 --- [main] run.halo.app.handler.file.FileHandlers : Registered 9 file handler(s)
2022-04-04 15:51:15.492 INFO 7 --- [main] o.s.b.a.e.web.EndpointLinksResolver : Exposing 4 endpoint(s) beneath base path '/api/admin/actuator'
2022-04-04 15:51:15.554 INFO 7 --- [main] o.e.j.s.h.ContextHandler.application : Initializing Spring DispatcherServlet 'dispatcherServlet'
2022-04-04 15:51:15.554 INFO 7 --- [main] o.s.web.servlet.DispatcherServlet : Initializing Servlet 'dispatcherServlet'
2022-04-04 15:51:15.556 INFO 7 --- [main] o.s.web.servlet.DispatcherServlet : Completed initialization in 2 ms
2022-04-04 15:51:15.568 INFO 7 --- [main] o.e.jetty.server.AbstractConnector : Started ServerConnector@1d99ee1b{HTTP/1.1, (http/1.1)}{0.0.0.0:8090}
2022-04-04 15:51:15.569 INFO 7 --- [main] o.s.b.web.embedded.jetty.JettyWebServer : Jetty started on port(s) 8090 (http/1.1) with context path '/'
2022-04-04 15:51:15.591 INFO 7 --- [main] run.halo.app.Application : Started Application in 11.307 seconds (JVM running for 11.818)
2022-04-04 15:51:15.592 INFO 7 --- [main] run.halo.app.listener.StartedListener : Starting migrate database...
2022-04-04 15:51:15.663 INFO 7 --- [main] o.f.c.internal.license.VersionPrinter : Flyway Community Edition 7.15.0 by Redgate
2022-04-04 15:51:15.664 INFO 7 --- [main] o.f.c.i.database.base.BaseDatabaseType : Database: jdbc:h2:file:~/.halo/db/halo (H2 1.4)
2022-04-04 15:51:15.717 INFO 7 --- [main] o.f.core.internal.command.DbRepair : Successfully repaired schema history table "PUBLIC"."flyway_schema_history" (execution time 00:00.031s).
2022-04-04 15:51:15.717 INFO 7 --- [main] o.f.core.internal.command.DbRepair : Manual cleanup of the remaining effects of the failed migration may still be required.
2022-04-04 15:51:15.721 INFO 7 --- [main] o.f.c.internal.license.VersionPrinter : Flyway Community Edition 7.15.0 by Redgate
2022-04-04 15:51:15.732 INFO 7 --- [main] o.f.core.internal.command.DbValidate : Successfully validated 10 migrations (execution time 00:00.007s)
2022-04-04 15:51:15.744 INFO 7 --- [main] o.f.core.internal.command.DbMigrate : Current version of schema "PUBLIC": 5
2022-04-04 15:51:15.755 INFO 7 --- [main] o.f.core.internal.command.DbMigrate : Migrating schema "PUBLIC" to version "6 - migrate create contents table"
2022-04-04 15:51:15.764 ERROR 7 --- [main] o.f.core.internal.command.DbMigrate : Migration of schema "PUBLIC" to version "6 - migrate create contents table" failed! Please restore backups and roll back database and code!
2022-04-04 15:51:15.778 INFO 7 --- [main] ConditionEvaluationReportLoggingListener :
Error starting ApplicationContext. To display the conditions report re-run your application with 'debug' enabled.
2022-04-04 15:51:15.797 ERROR 7 --- [main] o.s.boot.SpringApplication : Application run failed
org.flywaydb.core.internal.command.DbMigrate$FlywayMigrateException: Migration V6__migrate_create_contents_table.sql failed
------------------------------------------------------
SQL State : 23505
Error Code : 23505
Message : Unique index or primary key violation: "PRIMARY KEY ON PUBLIC.CONTENTS(POST_ID) [1, TIMESTAMP '2022-03-01 14:03:27.247', TIMESTAMP '2022-03-08 21:57:22.958', SPACE(770 /* table: 74 id: 2987 */), 43, SPACE(456 /* table: 74 id: 2988 */), 43, 0]"; SQL statement:
-- Migrate post content to contents table
INSERT INTO contents(post_id, status, patch_log_id, head_patch_log_id, content, original_content, create_time,
update_time)
SELECT id,
status,
id,
id,
format_content,
original_content,
create_time,
update_time
FROM posts [23505-199]
Location : migration/V6__migrate_create_contents_table.sql (/application/BOOT-INF/classes/migration/V6__migrate_create_contents_table.sql)
Line : 2
Statement : -- Migrate post content to contents table
INSERT INTO contents(post_id, status, patch_log_id, head_patch_log_id, content, original_content, create_time,
update_time)
SELECT id,
status,
id,
id,
format_content,
original_content,
create_time,
update_time
FROM posts
at org.flywaydb.core.internal.command.DbMigrate.doMigrateGroup(DbMigrate.java:378) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.command.DbMigrate.lambda$applyMigrations$1(DbMigrate.java:271) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.jdbc.TransactionalExecutionTemplate.execute(TransactionalExecutionTemplate.java:66) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.command.DbMigrate.applyMigrations(DbMigrate.java:270) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.command.DbMigrate.migrateGroup(DbMigrate.java:243) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.command.DbMigrate.lambda$migrateAll$0(DbMigrate.java:141) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.jdbc.TableLockingExecutionTemplate$1.call(TableLockingExecutionTemplate.java:38) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.jdbc.TransactionalExecutionTemplate.execute(TransactionalExecutionTemplate.java:66) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.jdbc.TableLockingExecutionTemplate.execute(TableLockingExecutionTemplate.java:33) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.database.base.Connection.lock(Connection.java:103) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.schemahistory.JdbcTableSchemaHistory.lock(JdbcTableSchemaHistory.java:141) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.command.DbMigrate.migrateAll(DbMigrate.java:141) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.command.DbMigrate.migrate(DbMigrate.java:101) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.Flyway$1.execute(Flyway.java:219) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.Flyway$1.execute(Flyway.java:170) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.Flyway.execute(Flyway.java:586) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.Flyway.migrate(Flyway.java:170) ~[flyway-core-7.15.0.jar:na]
at run.halo.app.listener.StartedListener.migrate(StartedListener.java:123) ~[classes/:1.5.1]
at run.halo.app.listener.StartedListener.onApplicationEvent(StartedListener.java:76) ~[classes/:1.5.1]
at run.halo.app.listener.StartedListener.onApplicationEvent(StartedListener.java:47) ~[classes/:1.5.1]
at org.springframework.context.event.SimpleApplicationEventMulticaster.doInvokeListener(SimpleApplicationEventMulticaster.java:176) ~[spring-context-5.3.17.jar:5.3.17]
at org.springframework.context.event.SimpleApplicationEventMulticaster.invokeListener(SimpleApplicationEventMulticaster.java:169) ~[spring-context-5.3.17.jar:5.3.17]
at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:143) ~[spring-context-5.3.17.jar:5.3.17]
at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:421) ~[spring-context-5.3.17.jar:5.3.17]
at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:378) ~[spring-context-5.3.17.jar:5.3.17]
at org.springframework.boot.context.event.EventPublishingRunListener.started(EventPublishingRunListener.java:105) ~[spring-boot-2.5.11.jar:2.5.11]
at org.springframework.boot.SpringApplicationRunListeners.lambda$started$5(SpringApplicationRunListeners.java:75) ~[spring-boot-2.5.11.jar:2.5.11]
at java.base/java.util.ArrayList.forEach(Unknown Source) ~[na:na]
at org.springframework.boot.SpringApplicationRunListeners.doWithListeners(SpringApplicationRunListeners.java:117) ~[spring-boot-2.5.11.jar:2.5.11]
at org.springframework.boot.SpringApplicationRunListeners.doWithListeners(SpringApplicationRunListeners.java:111) ~[spring-boot-2.5.11.jar:2.5.11]
at org.springframework.boot.SpringApplicationRunListeners.started(SpringApplicationRunListeners.java:75) ~[spring-boot-2.5.11.jar:2.5.11]
at org.springframework.boot.SpringApplication.run(SpringApplication.java:345) ~[spring-boot-2.5.11.jar:2.5.11]
at org.springframework.boot.SpringApplication.run(SpringApplication.java:1365) ~[spring-boot-2.5.11.jar:2.5.11]
at org.springframework.boot.SpringApplication.run(SpringApplication.java:1354) ~[spring-boot-2.5.11.jar:2.5.11]
at run.halo.app.Application.main(Application.java:21) ~[classes/:1.5.1]
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:na]
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) ~[na:na]
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[na:na]
at java.base/java.lang.reflect.Method.invoke(Unknown Source) ~[na:na]
at org.springframework.boot.loader.MainMethodRunner.run(MainMethodRunner.java:49) ~[application/:na]
at org.springframework.boot.loader.Launcher.launch(Launcher.java:108) ~[application/:na]
at org.springframework.boot.loader.Launcher.launch(Launcher.java:58) ~[application/:na]
at org.springframework.boot.loader.JarLauncher.main(JarLauncher.java:88) ~[application/:na]
Caused by: org.flywaydb.core.internal.sqlscript.FlywaySqlScriptException: Migration V6__migrate_create_contents_table.sql failed
------------------------------------------------------
SQL State : 23505
Error Code : 23505
Message : Unique index or primary key violation: "PRIMARY KEY ON PUBLIC.CONTENTS(POST_ID) [1, TIMESTAMP '2022-03-01 14:03:27.247', TIMESTAMP '2022-03-08 21:57:22.958', SPACE(770 /* table: 74 id: 2987 */), 43, SPACE(456 /* table: 74 id: 2988 */), 43, 0]"; SQL statement:
-- Migrate post content to contents table
INSERT INTO contents(post_id, status, patch_log_id, head_patch_log_id, content, original_content, create_time,
update_time)
SELECT id,
status,
id,
id,
format_content,
original_content,
create_time,
update_time
FROM posts [23505-199]
Location : migration/V6__migrate_create_contents_table.sql (/application/BOOT-INF/classes/migration/V6__migrate_create_contents_table.sql)
Line : 2
Statement : -- Migrate post content to contents table
INSERT INTO contents(post_id, status, patch_log_id, head_patch_log_id, content, original_content, create_time,
update_time)
SELECT id,
status,
id,
id,
format_content,
original_content,
create_time,
update_time
FROM posts
at org.flywaydb.core.internal.sqlscript.DefaultSqlScriptExecutor.handleException(DefaultSqlScriptExecutor.java:277) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.sqlscript.DefaultSqlScriptExecutor.executeStatement(DefaultSqlScriptExecutor.java:224) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.sqlscript.DefaultSqlScriptExecutor.execute(DefaultSqlScriptExecutor.java:128) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.resolver.sql.SqlMigrationExecutor.executeOnce(SqlMigrationExecutor.java:78) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.resolver.sql.SqlMigrationExecutor.lambda$execute$0(SqlMigrationExecutor.java:67) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.database.DefaultExecutionStrategy.execute(DefaultExecutionStrategy.java:27) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.resolver.sql.SqlMigrationExecutor.execute(SqlMigrationExecutor.java:66) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.command.DbMigrate.doMigrateGroup(DbMigrate.java:370) ~[flyway-core-7.15.0.jar:na]
... 42 common frames omitted
Caused by: org.h2.jdbc.JdbcSQLIntegrityConstraintViolationException: Unique index or primary key violation: "PRIMARY KEY ON PUBLIC.CONTENTS(POST_ID) [1, TIMESTAMP '2022-03-01 14:03:27.247', TIMESTAMP '2022-03-08 21:57:22.958', SPACE(770 /* table: 74 id: 2987 */), 43, SPACE(456 /* table: 74 id: 2988 */), 43, 0]"; SQL statement:
-- Migrate post content to contents table
INSERT INTO contents(post_id, status, patch_log_id, head_patch_log_id, content, original_content, create_time,
update_time)
SELECT id,
status,
id,
id,
format_content,
original_content,
create_time,
update_time
FROM posts [23505-199]
at org.h2.message.DbException.getJdbcSQLException(DbException.java:457) ~[h2-1.4.199.jar:1.4.199]
at org.h2.message.DbException.getJdbcSQLException(DbException.java:427) ~[h2-1.4.199.jar:1.4.199]
at org.h2.message.DbException.get(DbException.java:205) ~[h2-1.4.199.jar:1.4.199]
at org.h2.message.DbException.get(DbException.java:181) ~[h2-1.4.199.jar:1.4.199]
at org.h2.mvstore.db.MVPrimaryIndex.add(MVPrimaryIndex.java:131) ~[h2-1.4.199.jar:1.4.199]
at org.h2.mvstore.db.MVTable.addRow(MVTable.java:546) ~[h2-1.4.199.jar:1.4.199]
at org.h2.command.dml.Insert.addRowImpl(Insert.java:251) ~[h2-1.4.199.jar:1.4.199]
at org.h2.command.dml.Insert.insertRows(Insert.java:207) ~[h2-1.4.199.jar:1.4.199]
at org.h2.command.dml.Insert.update(Insert.java:132) ~[h2-1.4.199.jar:1.4.199]
at org.h2.command.CommandContainer.update(CommandContainer.java:133) ~[h2-1.4.199.jar:1.4.199]
at org.h2.command.Command.executeUpdate(Command.java:267) ~[h2-1.4.199.jar:1.4.199]
at org.h2.jdbc.JdbcStatement.executeInternal(JdbcStatement.java:233) ~[h2-1.4.199.jar:1.4.199]
at org.h2.jdbc.JdbcStatement.execute(JdbcStatement.java:205) ~[h2-1.4.199.jar:1.4.199]
at org.flywaydb.core.internal.jdbc.JdbcTemplate.executeStatement(JdbcTemplate.java:241) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.sqlscript.ParsedSqlStatement.execute(ParsedSqlStatement.java:111) ~[flyway-core-7.15.0.jar:na]
at org.flywaydb.core.internal.sqlscript.DefaultSqlScriptExecutor.executeStatement(DefaultSqlScriptExecutor.java:212) ~[flyway-core-7.15.0.jar:na]
... 48 common frames omitted
2022-04-04 15:51:15.808 INFO 7 --- [main] o.e.jetty.server.AbstractConnector : Stopped ServerConnector@1d99ee1b{HTTP/1.1, (http/1.1)}{0.0.0.0:8090}
2022-04-04 15:51:15.809 INFO 7 --- [main] org.eclipse.jetty.server.session : node0 Stopped scavenging
2022-04-04 15:51:15.810 INFO 7 --- [main] o.e.j.s.h.ContextHandler.application : Destroying Spring FrameworkServlet 'dispatcherServlet'
2022-04-04 15:51:15.811 INFO 7 --- [main] o.e.jetty.server.handler.ContextHandler : Stopped o.s.b.w.e.j.JettyEmbeddedWebAppContext@25c3a441{application,/,[file:///tmp/jetty-docbase.8090.7177670233149472716/, jar:file:/application/BOOT-INF/lib/springfox-swagger-ui-3.0.0.jar!/META-INF/resources],STOPPED}
2022-04-04 15:51:15.827 INFO 7 --- [main] j.LocalContainerEntityManagerFactoryBean : Closing JPA EntityManagerFactory for persistence unit 'default'
2022-04-04 15:51:15.829 INFO 7 --- [main] com.zaxxer.hikari.HikariDataSource : HikariPool-1 - Shutdown initiated...
2022-04-04 15:51:15.858 INFO 7 --- [main] com.zaxxer.hikari.HikariDataSource : HikariPool-1 - Shutdown completed.
2022-04-04 15:51:18.737 INFO 7 --- [main] run.halo.app.Application : Starting Application v1.5.1 using Java 11.0.11 on 30041f06b4f7 with PID 7 (/application/BOOT-INF/classes started by root in /application)
2022-04-04 15:51:18.741 INFO 7 --- [main] run.halo.app.Application : No active profile set, falling back to 1 default profile: "default"
2022-04-04 15:51:20.010 INFO 7 --- [main] .s.d.r.c.RepositoryConfigurationDelegate : Multiple Spring Data modules found, entering strict repository configuration mode!
2022-04-04 15:51:20.010 INFO 7 --- [main] .s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2022-04-04 15:51:20.329 INFO 7 --- [main] .s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository scanning in 309 ms. Found 24 JPA repository interfaces.
2022-04-04 15:51:21.365 INFO 7 --- [main] org.eclipse.jetty.util.log : Logging initialized @3735ms to org.eclipse.jetty.util.log.Slf4jLog
2022-04-04 15:51:21.573 INFO 7 --- [main] o.s.b.w.e.j.JettyServletWebServerFactory : Server initialized with port: 8090
2022-04-04 15:51:21.580 INFO 7 --- [main] org.eclipse.jetty.server.Server : jetty-9.4.45.v20220203; built: 2022-02-03T09:14:34.105Z; git: 4a0c91c0be53805e3fcffdcdcc9587d5301863db; jvm 11.0.11+9
2022-04-04 15:51:21.635 INFO 7 --- [main] o.e.j.s.h.ContextHandler.application : Initializing Spring embedded WebApplicationContext
2022-04-04 15:51:21.636 INFO 7 --- [main] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 2828 ms
2022-04-04 15:51:22.166 INFO 7 --- [main] run.halo.app.config.HaloConfiguration : Halo cache store load impl : [class run.halo.app.cache.InMemoryCacheStore]