[SOLVED]Failing to start after computer restart

lildevil · March 1, 2022, 6:12pm

Hi,

I've been having some issues with my metabase not starting up after reboot.

I was running on version 0.41.5 and noticed that my dashboards were loading extremely slowly so I decided to give it a restarted. At which point, it wouldn't restart. So I figured since I'm running on an older version, that I would update it to the latest 0.4.2 but it still wouldn't restart.

In the log this is what it's giving me

Mar  1 17:28:51 Metabase metabase[13351]: 2022-03-01 17:28:51,535 INFO metabase.util :: Maximum memory available to JVM: 3.8 GB

Mar 1 17:28:59 Metabase metabase[13351]: 2022-03-01 17:28:59,311 INFO util.encryption :: Saved credentials encryption is DISABLED for this Metabase instance.
Mar 1 17:28:59 Metabase metabase[13351]: For more information, see https :// metabase.com/docs/latest/operations-guide/encrypting-database-details-at-rest.html
Mar 1 17:29:00 Metabase metabase[13351]: 2022-03-01 17:29:00,936 INFO driver.impl :: #033[34mRegistered abstract driver :sql#033[0m
Mar 1 17:29:00 Metabase metabase[13351]: #033[36m ⮦ Load driver :sql took 341.8 ms#033[0m
Mar 1 17:29:00 Metabase metabase[13351]: 2022-03-01 17:29:00,943 INFO driver.impl :: #033[34mRegistered abstract driver :sql-jdbc#033[0m (parents: [:sql])
Mar 1 17:29:00 Metabase metabase[13351]: #033[32mLoad driver :sql-jdbc took 351.0 ms#033[0m
Mar 1 17:29:00 Metabase metabase[13351]: 2022-03-01 17:29:00,945 INFO driver.impl :: #033[34mRegistered driver :h2#033[0m (parents: [:sql-jdbc])
Mar 1 17:29:00 Metabase metabase[13351]: 2022-03-01 17:29:00,957 INFO driver.impl :: #033[34mRegistered driver :mysql#033[0m (parents: [:sql-jdbc])
Mar 1 17:29:00 Metabase metabase[13351]: 2022-03-01 17:29:00,968 INFO driver.impl :: #033[34mRegistered driver :postgres#033[0m (parents: [:sql-jdbc])
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,439 INFO metabase.core ::
Mar 1 17:29:02 Metabase metabase[13351]: Metabase v0.42.2 (d6ff494 release-x.42.x)
Mar 1 17:29:02 Metabase metabase[13351]: Copyright © 2022 Metabase, Inc.
Mar 1 17:29:02 Metabase metabase[13351]: Metabase Enterprise Edition extensions are NOT PRESENT.
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,445 INFO metabase.core :: Starting Metabase in STANDALONE mode
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,468 INFO metabase.server :: Launching Embedded Jetty Webserver with config:
Mar 1 17:29:02 Metabase metabase[13351]: {:port 3000}
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,492 INFO metabase.core :: Starting Metabase version v0.42.2 (d6ff494 release-x.42.x) ...
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,510 INFO metabase.core :: System info:
Mar 1 17:29:02 Metabase metabase[13351]: {"file.encoding" "UTF-8",
Mar 1 17:29:02 Metabase metabase[13351]: "java.runtime.name" "OpenJDK Runtime Environment",
Mar 1 17:29:02 Metabase metabase[13351]: "java.runtime.version" "11.0.13+8-Ubuntu-0ubuntu1.18.04",
Mar 1 17:29:02 Metabase metabase[13351]: "java.vendor" "Ubuntu",
Mar 1 17:29:02 Metabase metabase[13351]: "java.vendor.url" "https ://ubuntu.com/",
Mar 1 17:29:02 Metabase metabase[13351]: "java.version" "11.0.13",
Mar 1 17:29:02 Metabase metabase[13351]: "java.vm.name" "OpenJDK 64-Bit Server VM",
Mar 1 17:29:02 Metabase metabase[13351]: "java.vm.version" "11.0.13+8-Ubuntu-0ubuntu1.18.04",
Mar 1 17:29:02 Metabase metabase[13351]: "os.name" "Linux",
Mar 1 17:29:02 Metabase metabase[13351]: "os.version" "5.4.0-96-generic",
Mar 1 17:29:02 Metabase metabase[13351]: "user.language" "en",
Mar 1 17:29:02 Metabase metabase[13351]: "user.timezone" "Atlantic/Reykjavik"}
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,511 INFO metabase.plugins :: Loading plugins in /home/brewer/metabase/plugins...
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,700 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :snowflake...#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,706 INFO driver.impl :: #033[34mRegistered driver :snowflake#033[0m (parents: [:sql-jdbc])
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,711 INFO plugins.dependencies :: Plugin 'Metabase Presto Driver' depends on plugin 'Presto Common Driver'
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,712 INFO plugins.dependencies :: Metabase Presto Driver dependency {:plugin Presto Common Driver} satisfied? false
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,712 INFO plugins.dependencies :: #033[33mPlugins with unsatisfied deps: ["Metabase Presto Driver"]#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,715 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :sqlite...#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,715 INFO driver.impl :: #033[34mRegistered driver :sqlite#033[0m (parents: [:sql-jdbc])
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,721 INFO plugins.dependencies :: #033[31mMetabase cannot initialize plugin Metabase Oracle Driver due to required dependencies.#033[0m Metabase requires the Oracle JDBC driver in order to connect to Oracle databases, but we can't ship it as part of Metabase due to licensing restrictions. See https :// metabase.com/docs/latest/administration-guide/databases/oracle.html for more details.
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,721 INFO plugins.dependencies :: Metabase Oracle Driver dependency {:class oracle.jdbc.OracleDriver} satisfied? false
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,721 INFO plugins.dependencies :: #033[33mPlugins with unsatisfied deps: ["Metabase Presto Driver" "Metabase Oracle Driver"]#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,725 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :redshift...#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,725 INFO driver.impl :: #033[34mRegistered driver :redshift#033[0m (parents: [:postgres])
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,728 INFO plugins.dependencies :: Plugin 'Metabase BigQuery Driver' depends on plugin 'Metabase Google Drivers Shared Dependencies'
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,728 INFO plugins.dependencies :: Metabase BigQuery Driver dependency {:plugin Metabase Google Drivers Shared Dependencies} satisfied? false
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,728 INFO plugins.dependencies :: #033[33mPlugins with unsatisfied deps: ["Metabase Presto Driver" "Metabase BigQuery Driver" "Metabase Oracle Driver"]#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,732 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :mongo...#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,733 INFO driver.impl :: #033[34mRegistered driver :mongo#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,738 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :bigquery-cloud-sdk...#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,738 INFO driver.impl :: #033[34mRegistered driver :bigquery-cloud-sdk#033[0m (parents: [:sql])
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,740 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :presto-common...#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,741 INFO driver.impl :: #033[34mRegistered abstract driver :presto-common#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,741 INFO plugins.dependencies :: Metabase Presto Driver dependency {:plugin Presto Common Driver} satisfied? true
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,742 DEBUG plugins.initialize :: #033[33mDependencies satisfied; these plugins will now be loaded: ["Metabase Presto Driver"]#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,742 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :presto...#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,742 INFO driver.impl :: #033[34mRegistered driver :presto#033[0m (parents: [:presto-common])
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,745 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :google...#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,745 INFO driver.impl :: #033[34mRegistered abstract driver :google#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,745 INFO plugins.dependencies :: Metabase BigQuery Driver dependency {:plugin Metabase Google Drivers Shared Dependencies} satisfied? true
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,746 DEBUG plugins.initialize :: #033[33mDependencies satisfied; these plugins will now be loaded: ["Metabase BigQuery Driver"]#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,746 WARN plugins.lazy-loaded-driver :: #033[31mWarning: plugin manifest for :bigquery does not include connection properties#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,747 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :bigquery...#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,747 INFO driver.impl :: #033[34mRegistered driver :bigquery#033[0m (parents: [:sql :google])
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,750 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :sqlserver...#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,750 INFO driver.impl :: #033[34mRegistered driver :sqlserver#033[0m (parents: [:sql-jdbc])
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,753 INFO plugins.dependencies :: Plugin 'Metabase Google Analytics Driver' depends on plugin 'Metabase Google Drivers Shared Dependencies'
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,753 INFO plugins.dependencies :: Metabase Google Analytics Driver dependency {:plugin Metabase Google Drivers Shared Dependencies} satisfied? true
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,754 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :googleanalytics...#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,754 INFO driver.impl :: #033[34mRegistered driver :googleanalytics#033[0m (parents: [:google])
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,791 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :hive-like...#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,791 INFO driver.impl :: #033[34mRegistered abstract driver :hive-like#033[0m (parents: [:sql-jdbc])
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,792 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :sparksql...#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,792 INFO driver.impl :: #033[34mRegistered driver :sparksql#033[0m (parents: [:hive-like])
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,794 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :druid...#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,795 INFO driver.impl :: #033[34mRegistered driver :druid#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,800 INFO plugins.dependencies :: Plugin 'Metabase Presto JDBC Driver' depends on plugin 'Presto Common Driver'
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,801 INFO plugins.dependencies :: Metabase Presto JDBC Driver dependency {:plugin Presto Common Driver} satisfied? true
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,801 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :presto-jdbc...#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,801 INFO driver.impl :: #033[34mRegistered driver :presto-jdbc#033[0m (parents: [:presto-common])
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,804 INFO plugins.dependencies :: #033[31mMetabase cannot initialize plugin Metabase Vertica Driver due to required dependencies.#033[0m Metabase requires the Vertica JDBC driver in order to connect to Vertica databases, but we can't ship it as part of Metabase due to licensing restrictions. See https ://metabase.com/docs/latest/administration-guide/databases/vertica.html for more details.
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,804 INFO plugins.dependencies :: Metabase Vertica Driver dependency {:class com.vertica.jdbc.Driver} satisfied? false
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,804 INFO plugins.dependencies :: #033[33mPlugins with unsatisfied deps: ["Metabase Vertica Driver" "Metabase Oracle Driver"]#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,805 INFO metabase.core :: Setting up and migrating Metabase DB. Please sit tight, this may take a minute...
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,807 INFO db.setup :: #033[36mVerifying mysql Database Connection ...#033[0m
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,856 INFO db.setup :: Successfully verified MySQL 5.7.37-0ubuntu0.18.04.1 application database connection.
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,857 INFO db.setup :: Running Database Migrations...
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,860 INFO db.setup :: Setting up Liquibase...
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,869 INFO db.setup :: Liquibase is ready.
Mar 1 17:29:02 Metabase metabase[13351]: 2022-03-01 17:29:02,870 INFO db.liquibase :: Checking if Database has unrun migrations...
Mar 1 17:29:03 Metabase metabase[13351]: 2022-03-01 17:29:03,680 WARN changelog.DatabaseChangeLog :: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case
Mar 1 17:29:03 Metabase metabase[13351]: message repeated 16 times: [ 2022-03-01 17:29:03,680 WARN changelog.DatabaseChangeLog :: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case]
Mar 1 17:29:03 Metabase metabase[13351]: 2022-03-01 17:29:03,681 WARN changelog.DatabaseChangeLog :: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case
Mar 1 17:29:03 Metabase metabase[13351]: message repeated 5 times: [ 2022-03-01 17:29:03,681 WARN changelog.DatabaseChangeLog :: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case]
Mar 1 17:29:03 Metabase metabase[13351]: 2022-03-01 17:29:03,687 INFO db.liquibase :: Database has unrun migrations. Waiting for migration lock to be cleared...
Mar 1 17:29:05 Metabase metabase[13351]: 2022-03-01 17:29:05,690 WARN metabase.util :: #033[31mauto-retry metabase.db.liquibase$wait_for_migration_lock_to_be_cleared$fn__14364@6c26d6f1: Database has migration lock; cannot run migrations. You can force-release these locks by running java -jar metabase.jar migrate release-locks.#033[0m
Mar 1 17:29:07 Metabase metabase[13351]: 2022-03-01 17:29:07,693 WARN metabase.util :: #033[31mauto-retry metabase.db.liquibase$wait_for_migration_lock_to_be_cleared$fn__14364@6c26d6f1: Database has migration lock; cannot run migrations. You can force-release these locks by running java -jar metabase.jar migrate release-locks.#033[0m
Mar 1 17:29:09 Metabase metabase[13351]: 2022-03-01 17:29:09,696 WARN metabase.util :: #033[31mauto-retry metabase.db.liquibase$wait_for_migration_lock_to_be_cleared$fn__14364@6c26d6f1: Database has migration lock; cannot run migrations. You can force-release these locks by running java -jar metabase.jar migrate release-locks.#033[0m
Mar 1 17:29:11 Metabase metabase[13351]: 2022-03-01 17:29:11,699 WARN metabase.util :: #033[31mauto-retry metabase.db.liquibase$wait_for_migration_lock_to_be_cleared$fn__14364@6c26d6f1: Database has migration lock; cannot run migrations. You can force-release these locks by running java -jar metabase.jar migrate release-locks.#033[0m
Mar 1 17:29:13 Metabase metabase[13351]: 2022-03-01 17:29:13,702 WARN metabase.util :: #033[31mauto-retry metabase.db.liquibase$wait_for_migration_lock_to_be_cleared$fn__14364@6c26d6f1: Database has migration lock; cannot run migrations. You can force-release these locks by running java -jar metabase.jar migrate release-locks.#033[0m

I've tried running "java -jar metabase.jar migrate release-locks" but still having the same issue.

I've noticed it indicated that is an issue with Vertica, but i do have a copy of it in my plugin folder. My only assumption is maybe it got corrupted?

If so how do I know which version of Vertica I need?

Thanks!

flamber · March 1, 2022, 6:18pm

Hi @lildevil

You're likely running multiple processes. Make sure that everything is shutdown:
ps aux | grep java
Then run java -jar metabase.jar migrate release-locks
When it has completed fully, then start Metabase normally

Shutting down Metabase while it's doing migrations is a good way to corrupt everything.
Alternatively, revert to your backup.

lildevil · March 1, 2022, 6:41pm

Hello,

Thanks for the reply.

I did try again to make sure everything is shutoff before restarting metabase. But still no luck and getting the same error.

I'm guessing I'm SOL.

flamber · March 1, 2022, 6:50pm

@lildevil What's the out of java -jar metabase.jar migrate release-locks ?
The command should basically connect to your MySQL application database and truncate the table DATABASECHANGELOGLOCK (or set the single row to 1 false null null)
Then try starting again.

Another good time to revise your backup strategy going forward.

lildevil · March 1, 2022, 6:58pm

Here is the output from java - jar metabase.jar migrate release-locks
Mar 1 18:32:48 Metabase metabase[23810]: 2022-03-01 18:32:48,558 INFO metabase.util :: Maximum memory available to JVM: 3.8 GB
Mar 1 18:32:56 Metabase metabase[23810]: 2022-03-01 18:32:56,457 INFO util.encryption :: Saved credentials encryption is DISABLED for this Metabase instance.
Mar 1 18:32:56 Metabase metabase[23810]: For more information, see http ://metabase.com/docs/latest/operations-guide/encrypting-database-details-at-rest.html
Mar 1 18:32:58 Metabase metabase[23810]: 2022-03-01 18:32:58,151 INFO driver.impl :: #033[34mRegistered abstract driver :sql#033[0m
Mar 1 18:32:58 Metabase metabase[23810]: #033[36m ⮦ Load driver :sql took 387.3 ms#033[0m
Mar 1 18:32:58 Metabase metabase[23810]: 2022-03-01 18:32:58,158 INFO driver.impl :: #033[34mRegistered abstract driver :sql-jdbc#033[0m (parents: [:sql])
Mar 1 18:32:58 Metabase metabase[23810]: #033[32mLoad driver :sql-jdbc took 397.1 ms#033[0m
Mar 1 18:32:58 Metabase metabase[23810]: 2022-03-01 18:32:58,160 INFO driver.impl :: #033[34mRegistered driver :h2#033[0m (parents: [:sql-jdbc])
Mar 1 18:32:58 Metabase metabase[23810]: 2022-03-01 18:32:58,173 INFO driver.impl :: #033[34mRegistered driver :mysql#033[0m (parents: [:sql-jdbc])
Mar 1 18:32:58 Metabase metabase[23810]: 2022-03-01 18:32:58,188 INFO driver.impl :: #033[34mRegistered driver :postgres#033[0m (parents: [:sql-jdbc])
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,603 INFO metabase.core ::
Mar 1 18:32:59 Metabase metabase[23810]: Metabase v0.42.2 (d6ff494 release-x.42.x)
Mar 1 18:32:59 Metabase metabase[23810]: Copyright © 2022 Metabase, Inc.
Mar 1 18:32:59 Metabase metabase[23810]: Metabase Enterprise Edition extensions are NOT PRESENT.
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,609 INFO metabase.core :: Starting Metabase in STANDALONE mode
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,632 INFO metabase.server :: Launching Embedded Jetty Webserver with config:
Mar 1 18:32:59 Metabase metabase[23810]: {:port 3000}
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,656 INFO metabase.core :: Starting Metabase version v0.42.2 (d6ff494 release-x.42.x) ...
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,673 INFO metabase.core :: System info:
Mar 1 18:32:59 Metabase metabase[23810]: {"file.encoding" "UTF-8",
Mar 1 18:32:59 Metabase metabase[23810]: "java.runtime.name" "OpenJDK Runtime Environment",
Mar 1 18:32:59 Metabase metabase[23810]: "java.runtime.version" "11.0.13+8-Ubuntu-0ubuntu1.18.04",
Mar 1 18:32:59 Metabase metabase[23810]: "java.vendor" "Ubuntu",
Mar 1 18:32:59 Metabase metabase[23810]: "java.vendor.url" "http ://ubuntu.com/",
Mar 1 18:32:59 Metabase metabase[23810]: "java.version" "11.0.13",
Mar 1 18:32:59 Metabase metabase[23810]: "java.vm.name" "OpenJDK 64-Bit Server VM",
Mar 1 18:32:59 Metabase metabase[23810]: "java.vm.version" "11.0.13+8-Ubuntu-0ubuntu1.18.04",
Mar 1 18:32:59 Metabase metabase[23810]: "os.name" "Linux",
Mar 1 18:32:59 Metabase metabase[23810]: "os.version" "5.4.0-96-generic",
Mar 1 18:32:59 Metabase metabase[23810]: "user.language" "en",
Mar 1 18:32:59 Metabase metabase[23810]: "user.timezone" "Atlantic/Reykjavik"}
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,674 INFO metabase.plugins :: Loading plugins in /home/brewer/metabase/plugins...
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,862 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :snowflake...#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,866 INFO driver.impl :: #033[34mRegistered driver :snowflake#033[0m (parents: [:sql-jdbc])
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,871 INFO plugins.dependencies :: Plugin 'Metabase Presto Driver' depends on plugin 'Presto Common Driver'
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,872 INFO plugins.dependencies :: Metabase Presto Driver dependency {:plugin Presto Common Driver} satisfied? false
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,872 INFO plugins.dependencies :: #033[33mPlugins with unsatisfied deps: ["Metabase Presto Driver"]#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,875 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :sqlite...#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,875 INFO driver.impl :: #033[34mRegistered driver :sqlite#033[0m (parents: [:sql-jdbc])
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,880 INFO plugins.dependencies :: #033[31mMetabase cannot initialize plugin Metabase Oracle Driver due to required dependencies.#033[0m Metabase requires the Oracle JDBC driver in order to connect to Oracle databases, but we can't ship it as part of Metabase due to licensing restrictions. See http ://metabase.com/docs/latest/administration-guide/databases/oracle.html for more details.
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,881 INFO plugins.dependencies :: Metabase Oracle Driver dependency {:class oracle.jdbc.OracleDriver} satisfied? false
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,882 INFO plugins.dependencies :: #033[33mPlugins with unsatisfied deps: ["Metabase Presto Driver" "Metabase Oracle Driver"]#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,885 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :redshift...#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,886 INFO driver.impl :: #033[34mRegistered driver :redshift#033[0m (parents: [:postgres])
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,888 INFO plugins.dependencies :: Plugin 'Metabase BigQuery Driver' depends on plugin 'Metabase Google Drivers Shared Dependencies'
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,888 INFO plugins.dependencies :: Metabase BigQuery Driver dependency {:plugin Metabase Google Drivers Shared Dependencies} satisfied? false
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,889 INFO plugins.dependencies :: #033[33mPlugins with unsatisfied deps: ["Metabase Presto Driver" "Metabase BigQuery Driver" "Metabase Oracle Driver"]#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,893 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :mongo...#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,893 INFO driver.impl :: #033[34mRegistered driver :mongo#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,898 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :bigquery-cloud-sdk...#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,899 INFO driver.impl :: #033[34mRegistered driver :bigquery-cloud-sdk#033[0m (parents: [:sql])
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,901 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :presto-common...#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,901 INFO driver.impl :: #033[34mRegistered abstract driver :presto-common#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,902 INFO plugins.dependencies :: Metabase Presto Driver dependency {:plugin Presto Common Driver} satisfied? true
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,902 DEBUG plugins.initialize :: #033[33mDependencies satisfied; these plugins will now be loaded: ["Metabase Presto Driver"]#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,903 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :presto...#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,903 INFO driver.impl :: #033[34mRegistered driver :presto#033[0m (parents: [:presto-common])
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,906 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :google...#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,906 INFO driver.impl :: #033[34mRegistered abstract driver :google#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,907 INFO plugins.dependencies :: Metabase BigQuery Driver dependency {:plugin Metabase Google Drivers Shared Dependencies} satisfied? true
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,908 DEBUG plugins.initialize :: #033[33mDependencies satisfied; these plugins will now be loaded: ["Metabase BigQuery Driver"]#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,908 WARN plugins.lazy-loaded-driver :: #033[31mWarning: plugin manifest for :bigquery does not include connection properties#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,908 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :bigquery...#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,909 INFO driver.impl :: #033[34mRegistered driver :bigquery#033[0m (parents: [:sql :google])
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,911 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :sqlserver...#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,912 INFO driver.impl :: #033[34mRegistered driver :sqlserver#033[0m (parents: [:sql-jdbc])
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,915 INFO plugins.dependencies :: Plugin 'Metabase Google Analytics Driver' depends on plugin 'Metabase Google Drivers Shared Dependencies'
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,915 INFO plugins.dependencies :: Metabase Google Analytics Driver dependency {:plugin Metabase Google Drivers Shared Dependencies} satisfied? true
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,915 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :googleanalytics...#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,916 INFO driver.impl :: #033[34mRegistered driver :googleanalytics#033[0m (parents: [:google])
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,931 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :hive-like...#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,932 INFO driver.impl :: #033[34mRegistered abstract driver :hive-like#033[0m (parents: [:sql-jdbc])
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,932 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :sparksql...#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,933 INFO driver.impl :: #033[34mRegistered driver :sparksql#033[0m (parents: [:hive-like])
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,935 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :druid...#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,935 INFO driver.impl :: #033[34mRegistered driver :druid#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,941 INFO plugins.dependencies :: Plugin 'Metabase Presto JDBC Driver' depends on plugin 'Presto Common Driver'
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,942 INFO plugins.dependencies :: Metabase Presto JDBC Driver dependency {:plugin Presto Common Driver} satisfied? true
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,942 DEBUG plugins.lazy-loaded-driver :: #033[35mRegistering lazy loading driver :presto-jdbc...#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,942 INFO driver.impl :: #033[34mRegistered driver :presto-jdbc#033[0m (parents: [:presto-common])
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,945 INFO plugins.dependencies :: #033[31mMetabase cannot initialize plugin Metabase Vertica Driver due to required dependencies.#033[0m Metabase requires the Vertica JDBC driver in order to connect to Vertica databases, but we can't ship it as part of Metabase due to licensing restrictions. See http ://metabase.com/docs/latest/administration-guide/databases/vertica.html for more details.
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,945 INFO plugins.dependencies :: Metabase Vertica Driver dependency {:class com.vertica.jdbc.Driver} satisfied? false
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,946 INFO plugins.dependencies :: #033[33mPlugins with unsatisfied deps: ["Metabase Vertica Driver" "Metabase Oracle Driver"]#033[0m
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,946 INFO metabase.core :: Setting up and migrating Metabase DB. Please sit tight, this may take a minute...
Mar 1 18:32:59 Metabase metabase[23810]: 2022-03-01 18:32:59,948 INFO db.setup :: #033[36mVerifying mysql Database Connection ...#033[0m
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:32:59,999 INFO db.setup :: Successfully verified MySQL 5.7.37-0ubuntu0.18.04.1 application database connection.
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:33:00,000 INFO db.setup :: Running Database Migrations...
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:33:00,003 INFO db.setup :: Setting up Liquibase...
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:33:00,012 INFO db.setup :: Liquibase is ready.
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:33:00,012 INFO db.liquibase :: Checking if Database has unrun migrations...
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:33:00,827 WARN changelog.DatabaseChangeLog :: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case
Mar 1 18:33:00 Metabase metabase[23810]: message repeated 5 times: [ 2022-03-01 18:33:00,827 WARN changelog.DatabaseChangeLog :: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case]
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:33:00,828 WARN changelog.DatabaseChangeLog :: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case
Mar 1 18:33:00 Metabase metabase[23810]: message repeated 12 times: [ 2022-03-01 18:33:00,828 WARN changelog.DatabaseChangeLog :: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case]
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:33:00,829 WARN changelog.DatabaseChangeLog :: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case
Mar 1 18:33:00 Metabase metabase[23810]: message repeated 3 times: [ 2022-03-01 18:33:00,829 WARN changelog.DatabaseChangeLog :: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case]
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:33:00,837 INFO db.liquibase :: Database has unrun migrations. Waiting for migration lock to be cleared...
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:33:00,000 INFO db.setup :: Running Database Migrations...
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:33:00,003 INFO db.setup :: Setting up Liquibase...
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:33:00,012 INFO db.setup :: Liquibase is ready.
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:33:00,012 INFO db.liquibase :: Checking if Database has unrun migrations...
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:33:00,827 WARN changelog.DatabaseChangeLog :: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case
Mar 1 18:33:00 Metabase metabase[23810]: message repeated 5 times: [ 2022-03-01 18:33:00,827 WARN changelog.DatabaseChangeLog :: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case]
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:33:00,828 WARN changelog.DatabaseChangeLog :: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case
Mar 1 18:33:00 Metabase metabase[23810]: message repeated 12 times: [ 2022-03-01 18:33:00,828 WARN changelog.DatabaseChangeLog :: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case]
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:33:00,829 WARN changelog.DatabaseChangeLog :: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case
Mar 1 18:33:00 Metabase metabase[23810]: message repeated 3 times: [ 2022-03-01 18:33:00,829 WARN changelog.DatabaseChangeLog :: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case]
Mar 1 18:33:00 Metabase metabase[23810]: 2022-03-01 18:33:00,837 INFO db.liquibase :: Database has unrun migrations. Waiting for migration lock to be cleared...
Mar 1 18:33:02 Metabase metabase[23810]: 2022-03-01 18:33:02,841 WARN metabase.util :: #033[31mauto-retry metabase.db.liquibase$wait_for_migration_lock_to_be_cleared$fn__14364@6c26d6f1: Database has migration lock; cannot run migrations. You can force-release these locks by running java -jar metabase.jar migrate release-locks.#033[0m
Mar 1 18:33:04 Metabase metabase[23810]: 2022-03-01 18:33:04,844 WARN metabase.util :: #033[31mauto-retry metabase.db.liquibase$wait_for_migration_lock_to_be_cleared$fn__14364@6c26d6f1: Database has migration lock; cannot run migrations. You can force-release these locks by running java -jar metabase.jar migrate release-locks.#033[0m
Mar 1 18:33:06 Metabase metabase[23810]: 2022-03-01 18:33:06,847 WARN metabase.util :: #033[31mauto-retry metabase.db.liquibase$wait_for_migration_lock_to_be_cleared$fn__14364@6c26d6f1: Database has migration lock; cannot run migrations. You can force-release these locks by running java -jar metabase.jar migrate release-locks.#033[0m
Mar 1 18:33:08 Metabase metabase[23810]: 2022-03-01 18:33:08,850 WARN metabase.util :: #033[31mauto-retry metabase.db.liquibase$wait_for_migration_lock_to_be_cleared$fn__14364@6c26d6f1: Database has migration lock; cannot run migrations. You can force-release these locks by running java -jar metabase.jar migrate release-locks.#033[0m
Mar 1 18:33:10 Metabase metabase[23810]: 2022-03-01 18:33:10,852 WARN metabase.util :: #033[31mauto-retry metabase.db.liquibase$wait_for_migration_lock_to_be_cleared$fn__14364@6c26d6f1: Database has migration lock; cannot run migrations. You can force-release these locks by running java -jar metabase.jar migrate release-locks.#033[0m
Mar 1 18:33:12 Metabase metabase[23810]: 2022-03-01 18:33:12,858 ERROR metabase.core :: Metabase Initialization FAILED
Mar 1 18:33:12 Metabase metabase[23810]: liquibase.exception.LockException: Database has migration lock; cannot run migrations. You can force-release these locks by running java -jar metabase.jar migrate release-locks.
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.liquibase$wait_for_migration_lock_to_be_cleared$fn__14364.invoke(liquibase.clj:109)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.util$do_with_auto_retries.invokeStatic(util.clj:471)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.util$do_with_auto_retries.invoke(util.clj:463)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.util$do_with_auto_retries.invokeStatic(util.clj:478)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.util$do_with_auto_retries.invoke(util.clj:463)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.util$do_with_auto_retries.invokeStatic(util.clj:478)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.util$do_with_auto_retries.invoke(util.clj:463)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.util$do_with_auto_retries.invokeStatic(util.clj:478)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.util$do_with_auto_retries.invoke(util.clj:463)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.util$do_with_auto_retries.invokeStatic(util.clj:478)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.util$do_with_auto_retries.invoke(util.clj:463)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.util$do_with_auto_retries.invokeStatic(util.clj:478)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.util$do_with_auto_retries.invoke(util.clj:463)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.liquibase$wait_for_migration_lock_to_be_cleared.invokeStatic(liquibase.clj:104)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.liquibase$wait_for_migration_lock_to_be_cleared.invoke(liquibase.clj:100)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.liquibase$migrate_up_if_needed_BANG_.invokeStatic(liquibase.clj:120)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.liquibase$migrate_up_if_needed_BANG_.invoke(liquibase.clj:114)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.setup$migrate_BANG_$fn__34014$fn__34015.invoke(setup.clj:68)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.liquibase$do_with_liquibase.invokeStatic(liquibase.clj:38)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.liquibase$do_with_liquibase.invoke(liquibase.clj:30)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.setup$migrate_BANG_$fn__34014.invoke(setup.clj:63)
Mar 1 18:33:12 Metabase metabase[23810]: #011at clojure.java.jdbc$db_transaction_STAR_.invokeStatic(jdbc.clj:807)
Mar 1 18:33:12 Metabase metabase[23810]: #011at clojure.java.jdbc$db_transaction_STAR_.invoke(jdbc.clj:776)
Mar 1 18:33:12 Metabase metabase[23810]: #011at clojure.java.jdbc$db_transaction_STAR_.invokeStatic(jdbc.clj:852)
Mar 1 18:33:12 Metabase metabase[23810]: #011at clojure.java.jdbc$db_transaction_STAR_.invoke(jdbc.clj:776)
Mar 1 18:33:12 Metabase metabase[23810]: #011at clojure.java.jdbc$db_transaction_STAR_.invokeStatic(jdbc.clj:789)
Mar 1 18:33:12 Metabase metabase[23810]: #011at clojure.java.jdbc$db_transaction_STAR_.invoke(jdbc.clj:776)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.setup$migrate_BANG_.invokeStatic(setup.clj:54)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.setup$migrate_BANG_.invoke(setup.clj:40)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.setup$run_schema_migrations_BANG_.invokeStatic(setup.clj:119)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.setup$run_schema_migrations_BANG_.invoke(setup.clj:115)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.setup$setup_db_BANG_$fn__34065$fn__34066.invoke(setup.clj:143)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.util$do_with_us_locale.invokeStatic(util.clj:692)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.util$do_with_us_locale.invoke(util.clj:678)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.setup$setup_db_BANG_$fn__34065.invoke(setup.clj:141)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.setup$setup_db_BANG_.invokeStatic(setup.clj:140)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db.setup$setup_db_BANG_.invoke(setup.clj:136)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db$setup_db_BANG_$fn__34175.invoke(db.clj:61)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db$setup_db_BANG_.invokeStatic(db.clj:56)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.db$setup_db_BANG_.invoke(db.clj:51)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.core$init_BANG_.invokeStatic(core.clj:90)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.core$init_BANG_.invoke(core.clj:73)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.core$start_normally.invokeStatic(core.clj:131)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.core$start_normally.invoke(core.clj:125)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.core$_main.invokeStatic(core.clj:164)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.core$_main.doInvoke(core.clj:158)
Mar 1 18:33:12 Metabase metabase[23810]: #011at clojure.lang.RestFn.invoke(RestFn.java:397)
Mar 1 18:33:12 Metabase metabase[23810]: #011at clojure.lang.AFn.applyToHelper(AFn.java:152)
Mar 1 18:33:12 Metabase metabase[23810]: #011at clojure.lang.RestFn.applyTo(RestFn.java:132)
Mar 1 18:33:12 Metabase metabase[23810]: #011at metabase.core.main(Unknown Source)
Mar 1 18:33:12 Metabase metabase[23810]: 2022-03-01 18:33:12,869 INFO metabase.core :: Metabase Shutting Down ...
Mar 1 18:33:12 Metabase metabase[23810]: 2022-03-01 18:33:12,870 INFO metabase.server :: Shutting Down Embedded Jetty Webserver
Mar 1 18:33:12 Metabase metabase[23810]: 2022-03-01 18:33:12,884 INFO metabase.core :: Metabase Shutdown COMPLETE
Mar 1 18:33:13 Metabase systemd[1]: metabase.service: Main process exited, code=exited, status=1/FAILURE
Mar 1 18:33:13 Metabase systemd[1]: metabase.service: Failed with result 'exit-code'.

and correct, I really do need to revisit my backup strategy.

Thanks for the assistance!

flamber · March 1, 2022, 7:02pm

@lildevil Make a backup, then truncate the table DATABASECHANGELOGLOCK and start normally.

lildevil · March 1, 2022, 7:21pm

Ha Za! That did it!

Thank you very much for your help!