Initial setup on Debian (Ubuntu) Not Working

Hello,

I am able to run metabase via the java -jar metabase.jar command and can then access it via the browser. I then followed the directions at Running Metabase on Debian as a service with nginx completely. I cannot access metabase through the browser. "ps -aux" shows that the program is running as the proper user. netstat shows that the port 3000 is not open at all, though.

"systemctl status metabase" shows me:

Dec 09 15:01:02 dashboard systemd[1]: Started Metabase server.
Dec 09 15:01:05 dashboard metabase[4211]: Warning: protocol #'java-time.core/Amount is overwriting function abs
Dec 09 15:01:05 dashboard metabase[4211]: WARNING: abs already refers to: #'clojure.core/abs in namespace: java-time.core, being replaced by: #'java-time.core/abs
Dec 09 15:01:06 dashboard metabase[4211]: WARNING: abs already refers to: #'clojure.core/abs in namespace: java-time, being replaced by: #'java-time/abs

"journalctl -u metabase" shows the service started successfully with no errors and tells me:

Dec 09 15:01:54 dashboard systemd[1]: Stopped Metabase server.
Dec 09 15:01:54 dashboard systemd[1]: Started Metabase server.
Dec 09 15:01:57 dashboard metabase[4252]: Warning: protocol #'java-time.core/Amount is overwriting function abs
Dec 09 15:01:57 dashboard metabase[4252]: WARNING: abs already refers to: #'clojure.core/abs in namespace: java-time.core, being replaced by: #'java-time.core/abs
Dec 09 15:01:58 dashboard metabase[4252]: WARNING: abs already refers to: #'clojure.core/abs in namespace: java-time, being replaced by: #'java-time/abs
Dec 09 15:02:01 dashboard metabase[4252]: 2022-12-09 15:02:01,858 INFO metabase.util :: Maximum memory available to JVM: 982.0 MB
Dec 09 15:02:04 dashboard metabase[4252]: 2022-12-09 15:02:04,231 INFO util.encryption :: Saved credentials encryption is DISABLED for this Metabase instance.
Dec 09 15:02:04 dashboard metabase[4252]:  For more information, see https://metabase.com/docs/latest/operations-guide/encrypting-database-details-at-rest.html
Dec 09 15:02:04 dashboard metabase[4252]: WARNING: abs already refers to: #'clojure.core/abs in namespace: taoensso.encore, being replaced by: #'taoensso.encore/abs

I have set this up and had it working on another machine. Can't seem to figure out why it's failing.

Hi @momentumrising
It's not possible to tell what is going on without the full logs. I would guess it's a permission problem, but that's difficult to say.

How woukd I go about procuring the full logs? Where would they be?

@momentumrising They would be in file you've redirected syslog to save to https://www.metabase.com/docs/latest/installation-and-operation/running-metabase-on-debian#create-syslog-conf

The logs you are showing from journalctl -u metabase is only the beginning, it does not show Metabase is started, which would be this line:
INFO metabase.core Metabase Initialization COMPLETE

Here is the relevant output from the metabase.log file. Seems to be the main error is: "java.sql.SQLException: RSA public key is not available client side (option serverRsaPublicKeyFile not set)"

Dec 18 21:17:27 dashboard metabase[9372]: 2022-12-18 21:17:27,563 INFO metabase.core :: Setting up and migrating Metabase DB. Please sit tight, this may take a minute...
Dec 18 21:17:27 dashboard metabase[9372]: 2022-12-18 21:17:27,572 INFO db.setup :: #033[36mVerifying mysql Database Connection ...#033[0m
Dec 18 21:17:57 dashboard metabase[9372]: 2022-12-18 21:17:57,982 ERROR metabase.core :: Metabase Initialization FAILED
Dec 18 21:17:57 dashboard metabase[9372]: clojure.lang.ExceptionInfo: Unable to connect to Metabase mysql DB. {}
Dec 18 21:17:57 dashboard metabase[9372]: #011at metabase.db.setup$fn__36344$verify_db_connection__36349$fn__36350$fn__36351.invoke(setup.clj:100)
Dec 18 21:17:57 dashboard metabase[9372]: #011at metabase.db.setup$fn__36344$verify_db_connection__36349$fn__36350.invoke(setup.clj:98)
Dec 18 21:17:57 dashboard metabase[9372]: #011at metabase.db.setup$fn__36344$verify_db_connection__36349.invoke(setup.clj:90)
Dec 18 21:17:57 dashboard metabase[9372]: #011at metabase.db.setup$fn__36426$setup_db_BANG___36431$fn__36432$fn__36435$fn__36436.invoke(setup.clj:145)
Dec 18 21:17:57 dashboard metabase[9372]: #011at metabase.util$do_with_us_locale.invokeStatic(util.clj:707)
Dec 18 21:17:57 dashboard metabase[9372]: #011at metabase.util$do_with_us_locale.invoke(util.clj:693)
Dec 18 21:17:57 dashboard metabase[9372]: #011at metabase.db.setup$fn__36426$setup_db_BANG___36431$fn__36432$fn__36435.invoke(setup.clj:144)
Dec 18 21:17:57 dashboard metabase[9372]: #011at metabase.db.setup$fn__36426$setup_db_BANG___36431$fn__36432.invoke(setup.clj:143)
Dec 18 21:17:57 dashboard metabase[9372]: #011at metabase.db.setup$fn__36426$setup_db_BANG___36431.invoke(setup.clj:137)
Dec 18 21:17:57 dashboard metabase[9372]: #011at metabase.db$setup_db_BANG_$fn__36461.invoke(db.clj:65)
Dec 18 21:17:57 dashboard metabase[9372]: #011at metabase.db$setup_db_BANG_.invokeStatic(db.clj:60)
Dec 18 21:17:57 dashboard metabase[9372]: #011at metabase.db$setup_db_BANG_.invoke(db.clj:51)
Dec 18 21:17:57 dashboard metabase[9372]: #011at metabase.core$init_BANG__STAR_.invokeStatic(core.clj:101)
Dec 18 21:17:57 dashboard metabase[9372]: #011at metabase.core$init_BANG__STAR_.invoke(core.clj:87)
Dec 18 21:17:57 dashboard metabase[9372]: #011at metabase.core$init_BANG_.invokeStatic(core.clj:144)
Dec 18 21:17:57 dashboard metabase[9372]: #011at metabase.core$init_BANG_.invoke(core.clj:139)
Dec 18 21:17:58 dashboard metabase[9372]: #011at metabase.core$start_normally.invokeStatic(core.clj:156)
Dec 18 21:17:58 dashboard metabase[9372]: #011at metabase.core$start_normally.invoke(core.clj:150)
Dec 18 21:17:58 dashboard metabase[9372]: #011at metabase.core$_main.invokeStatic(core.clj:189)
Dec 18 21:17:58 dashboard metabase[9372]: #011at metabase.core$_main.doInvoke(core.clj:183)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.lang.RestFn.invoke(RestFn.java:397)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.lang.AFn.applyToHelper(AFn.java:152)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.lang.RestFn.applyTo(RestFn.java:132)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.lang.Var.applyTo(Var.java:705)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.core$apply.invokeStatic(core.clj:667)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.core$apply.invoke(core.clj:662)
Dec 18 21:17:58 dashboard metabase[9372]: #011at metabase.bootstrap$_main.invokeStatic(bootstrap.clj:23)
Dec 18 21:17:58 dashboard metabase[9372]: #011at metabase.bootstrap$_main.doInvoke(bootstrap.clj:20)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.lang.RestFn.invoke(RestFn.java:397)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.lang.AFn.applyToHelper(AFn.java:152)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.lang.RestFn.applyTo(RestFn.java:132)
Dec 18 21:17:58 dashboard metabase[9372]: #011at metabase.bootstrap.main(Unknown Source)
Dec 18 21:17:58 dashboard metabase[9372]: Caused by: java.sql.SQLException: Connections could not be acquired from the underlying database!
Dec 18 21:17:58 dashboard metabase[9372]: #011at com.mchange.v2.sql.SqlUtils.toSQLException(SqlUtils.java:118)
Dec 18 21:17:58 dashboard metabase[9372]: #011at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:692)
Dec 18 21:17:58 dashboard metabase[9372]: #011at com.mchange.v2.c3p0.impl.AbstractPoolBackedDataSource.getConnection(AbstractPoolBackedDataSource.java:140)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.java.jdbc$get_connection.invokeStatic(jdbc.clj:372)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.java.jdbc$get_connection.invoke(jdbc.clj:274)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.java.jdbc$db_query_with_resultset_STAR_.invokeStatic(jdbc.clj:1111)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.java.jdbc$db_query_with_resultset_STAR_.invoke(jdbc.clj:1093)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.java.jdbc$query.invokeStatic(jdbc.clj:1182)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.java.jdbc$query.invoke(jdbc.clj:1144)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.java.jdbc$query.invokeStatic(jdbc.clj:1160)
Dec 18 21:17:58 dashboard metabase[9372]: #011at clojure.java.jdbc$query.invoke(jdbc.clj:1144)
Dec 18 21:17:58 dashboard metabase[9372]: #011at metabase.driver.sql_jdbc.connection$can_connect_with_spec_QMARK_.invokeStatic(connection.clj:297)
Dec 18 21:17:58 dashboard metabase[9372]: #011at metabase.driver.sql_jdbc.connection$can_connect_with_spec_QMARK_.invoke(connection.clj:294)
Dec 18 21:17:58 dashboard metabase[9372]: #011at metabase.db.setup$fn__36344$verify_db_connection__36349$fn__36350$fn__36351.invoke(setup.clj:98)
Dec 18 21:17:58 dashboard metabase[9372]: #011... 31 more
Dec 18 21:17:58 dashboard metabase[9372]: Caused by: com.mchange.v2.resourcepool.CannotAcquireResourceException: A ResourcePool could not acquire a resource from its primary factory or source.
Dec 18 21:17:58 dashboard metabase[9372]: #011at com.mchange.v2.resourcepool.BasicResourcePool.awaitAvailable(BasicResourcePool.java:1507)
Dec 18 21:17:58 dashboard metabase[9372]: #011at com.mchange.v2.resourcepool.BasicResourcePool.prelimCheckoutResource(BasicResourcePool.java:644)
Dec 18 21:17:58 dashboard metabase[9372]: #011at com.mchange.v2.resourcepool.BasicResourcePool.checkoutResource(BasicResourcePool.java:554)
Dec 18 21:17:58 dashboard metabase[9372]: #011at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutAndMarkConnectionInUse(C3P0PooledConnectionPool.java:758)
Dec 18 21:17:58 dashboard metabase[9372]: #011at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:685)
Dec 18 21:17:58 dashboard metabase[9372]: #011... 43 more
Dec 18 21:17:58 dashboard metabase[9372]: Caused by: java.sql.SQLTransientConnectionException: Could not connect to address=(host=***.***.***(port=3306)(type=master) : RSA public key is not available client side (option serverRsaPublicKeyFile not set)
Dec 18 21:17:58 dashboard metabase[9372]: #011at org.mariadb.jdbc.internal.util.exceptions.ExceptionFactory.createException(ExceptionFactory.java:79)
Dec 18 21:17:58 dashboard metabase[9372]: #011at org.mariadb.jdbc.internal.util.exceptions.ExceptionFactory.create(ExceptionFactory.java:197)
Dec 18 21:17:58 dashboard metabase[9372]: #011at org.mariadb.jdbc.internal.protocol.AbstractConnectProtocol.connectWithoutProxy(AbstractConnectProtocol.java:1394)
Dec 18 21:17:58 dashboard metabase[9372]: #011at org.mariadb.jdbc.internal.util.Utils.retrieveProxy(Utils.java:635)
Dec 18 21:17:58 dashboard metabase[9372]: #011at org.mariadb.jdbc.MariaDbConnection.newConnection(MariaDbConnection.java:150)
Dec 18 21:17:58 dashboard metabase[9372]: #011at org.mariadb.jdbc.Driver.connect(Driver.java:89)
Dec 18 21:17:58 dashboard metabase[9372]: #011at java.sql/java.sql.DriverManager.getConnection(DriverManager.java:677)
Dec 18 21:17:58 dashboard metabase[9372]: #011at java.sql/java.sql.DriverManager.getConnection(DriverManager.java:189)
Dec 18 21:17:58 dashboard metabase[9372]: #011at metabase.db.data_source.DataSource.getConnection(data_source.clj:25)
Dec 18 21:17:58 dashboard metabase[9372]: #011at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:161)
Dec 18 21:17:58 dashboard metabase[9372]: #011at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:147)
Dec 18 21:17:58 dashboard metabase[9372]: #011at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager.acquireResource(C3P0PooledConnectionPool.java:202)
Dec 18 21:17:58 dashboard metabase[9372]: #011at com.mchange.v2.resourcepool.BasicResourcePool.doAcquire(BasicResourcePool.java:1176)
Dec 18 21:17:58 dashboard metabase[9372]: #011at com.mchange.v2.resourcepool.BasicResourcePool.doAcquireAndDecrementPendingAcquiresWithinLockOnSuccess(BasicResourcePool.java:1163)
Dec 18 21:17:58 dashboard metabase[9372]: #011at com.mchange.v2.resourcepool.BasicResourcePool.access$700(BasicResourcePool.java:44)
Dec 18 21:17:58 dashboard metabase[9372]: #011at com.mchange.v2.resourcepool.BasicResourcePool$ScatteredAcquireTask.run(BasicResourcePool.java:1908)
Dec 18 21:17:58 dashboard metabase[9372]: #011at com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:696)
Dec 18 21:17:58 dashboard metabase[9372]: Caused by: java.sql.SQLException: RSA public key is not available client side (option serverRsaPublicKeyFile not set)
Dec 18 21:17:58 dashboard metabase[9372]: #011at org.mariadb.jdbc.internal.com.send.authentication.CachingSha2PasswordPlugin.process(CachingSha2PasswordPlugin.java:189)
Dec 18 21:17:58 dashboard metabase[9372]: #011at org.mariadb.jdbc.internal.protocol.AbstractConnectProtocol.authenticationHandler(AbstractConnectProtocol.java:757)
Dec 18 21:17:58 dashboard metabase[9372]: #011at org.mariadb.jdbc.internal.protocol.AbstractConnectProtocol.createConnection(AbstractConnectProtocol.java:558)
Dec 18 21:17:58 dashboard metabase[9372]: #011at org.mariadb.jdbc.internal.protocol.AbstractConnectProtocol.connectWithoutProxy(AbstractConnectProtocol.java:1389)
Dec 18 21:17:58 dashboard metabase[9372]: #011... 14 more
Dec 18 21:17:58 dashboard metabase[9372]: 2022-12-18 21:17:58,003 INFO metabase.core :: Metabase Shutting Down ...
Dec 18 21:17:58 dashboard metabase[9372]: 2022-12-18 21:17:58,007 INFO metabase.server :: Shutting Down Embedded Jetty Webserver
Dec 18 21:17:58 dashboard metabase[9372]: 2022-12-18 21:17:58,020 INFO metabase.core :: Metabase Shutdown COMPLETE
Dec 18 21:18:01 dashboard metabase[9486]: Warning: protocol #'java-time.core/Amount is overwriting function abs
Dec 18 21:18:01 dashboard metabase[9486]: WARNING: abs already refers to: #'clojure.core/abs in namespace: java-time.core, being replaced by: #'java-time.core/abs
Dec 18 21:18:02 dashboard metabase[9486]: WARNING: abs already refers to: #'clojure.core/abs in namespace: java-time, being replaced by: #'java-time/abs
Dec 18 21:18:06 dashboard metabase[9486]: 2022-12-18 21:18:06,043 INFO metabase.util :: Maximum memory available to JVM: 982.0 MB
Dec 18 21:18:08 dashboard metabase[9486]: 2022-12-18 21:18:08,547 INFO util.encryption :: Saved credentials encryption is DISABLED for this Metabase instance.
Dec 18 21:18:08 dashboard metabase[9486]:  For more information, see https://metabase.com/docs/latest/operations-guide/encrypting-database-details-at-rest.html
Dec 18 21:18:08 dashboard metabase[9486]: WARNING: abs already refers to: #'clojure.core/abs in namespace: taoensso.encore, being replaced by: #'taoensso.encore/abs

@momentumrising Have a look at https://www.metabase.com/docs/latest/databases/connections/mysql

I read that article now. I changed to use "identified with mysql_native_password" in the user definition in the mysql server. Also I verified from the metabase server via nmap that it detects port 3306 on the mysql server as open. It seems to have a little bit different of an error set now:

Dec 22 06:19:18 dashboard metabase[2779]: 2022-12-22 06:19:18,285 INFO metabase.core :: Setting up and migrating Metabase DB. Please sit tight, this may take a minute...
Dec 22 06:19:18 dashboard metabase[2779]: 2022-12-22 06:19:18,291 INFO db.setup :: #033[36mVerifying mysql Database Connection ...#033[0m
Dec 22 06:19:48 dashboard metabase[2779]: 2022-12-22 06:19:48,698 ERROR metabase.core :: Metabase Initialization FAILED
Dec 22 06:19:48 dashboard metabase[2779]: clojure.lang.ExceptionInfo: Unable to connect to Metabase mysql DB. {}
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.setup$fn__36344$verify_db_connection__36349$fn__36350$fn__36351.invoke(setup.clj:100)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.setup$fn__36344$verify_db_connection__36349$fn__36350.invoke(setup.clj:98)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.setup$fn__36344$verify_db_connection__36349.invoke(setup.clj:90)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.setup$fn__36426$setup_db_BANG___36431$fn__36432$fn__36435$fn__36436.invoke(setup.clj:145)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.util$do_with_us_locale.invokeStatic(util.clj:707)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.util$do_with_us_locale.invoke(util.clj:693)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.setup$fn__36426$setup_db_BANG___36431$fn__36432$fn__36435.invoke(setup.clj:144)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.setup$fn__36426$setup_db_BANG___36431$fn__36432.invoke(setup.clj:143)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.setup$fn__36426$setup_db_BANG___36431.invoke(setup.clj:137)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db$setup_db_BANG_$fn__36461.invoke(db.clj:65)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db$setup_db_BANG_.invokeStatic(db.clj:60)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db$setup_db_BANG_.invoke(db.clj:51)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.core$init_BANG__STAR_.invokeStatic(core.clj:101)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.core$init_BANG__STAR_.invoke(core.clj:87)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.core$init_BANG_.invokeStatic(core.clj:144)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.core$init_BANG_.invoke(core.clj:139)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.core$start_normally.invokeStatic(core.clj:156)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.core$start_normally.invoke(core.clj:150)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.core$_main.invokeStatic(core.clj:189)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.core$_main.doInvoke(core.clj:183)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.lang.RestFn.invoke(RestFn.java:397)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.lang.AFn.applyToHelper(AFn.java:152)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.lang.RestFn.applyTo(RestFn.java:132)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.lang.Var.applyTo(Var.java:705)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.core$apply.invokeStatic(core.clj:667)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.core$apply.invoke(core.clj:662)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.bootstrap$_main.invokeStatic(bootstrap.clj:23)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.bootstrap$_main.doInvoke(bootstrap.clj:20)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.lang.RestFn.invoke(RestFn.java:397)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.lang.AFn.applyToHelper(AFn.java:152)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.lang.RestFn.applyTo(RestFn.java:132)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.bootstrap.main(Unknown Source)
Dec 22 06:19:48 dashboard metabase[2779]: Caused by: java.sql.SQLException: Connections could not be acquired from the underlying database!
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.sql.SqlUtils.toSQLException(SqlUtils.java:118)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:692)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.c3p0.impl.AbstractPoolBackedDataSource.getConnection(AbstractPoolBackedDataSource.java:140)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.java.jdbc$get_connection.invokeStatic(jdbc.clj:372)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.java.jdbc$get_connection.invoke(jdbc.clj:274)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.java.jdbc$db_query_with_resultset_STAR_.invokeStatic(jdbc.clj:1111)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.java.jdbc$db_query_with_resultset_STAR_.invoke(jdbc.clj:1093)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.java.jdbc$query.invokeStatic(jdbc.clj:1182)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.java.jdbc$query.invoke(jdbc.clj:1144)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.java.jdbc$query.invokeStatic(jdbc.clj:1160)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.java.jdbc$query.invoke(jdbc.clj:1144)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.driver.sql_jdbc.connection$can_connect_with_spec_QMARK_.invokeStatic(connection.clj:297)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.driver.sql_jdbc.connection$can_connect_with_spec_QMARK_.invoke(connection.clj:294)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.setup$fn__36344$verify_db_connection__36349$fn__36350$fn__36351.invoke(setup.clj:98)
Dec 22 06:19:48 dashboard metabase[2779]: #011... 31 more
Dec 22 06:19:48 dashboard metabase[2779]: Caused by: com.mchange.v2.resourcepool.CannotAcquireResourceException: A ResourcePool could not acquire a resource from its primary factory or source.
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.resourcepool.BasicResourcePool.awaitAvailable(BasicResourcePool.java:1507)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.resourcepool.BasicResourcePool.prelimCheckoutResource(BasicResourcePool.java:644)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.resourcepool.BasicResourcePool.checkoutResource(BasicResourcePool.java:554)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutAndMarkConnectionInUse(C3P0PooledConnectionPool.java:758)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:685)
Dec 22 06:19:48 dashboard metabase[2779]: #011... 43 more
Dec 22 06:19:48 dashboard metabase[2779]: Caused by: java.sql.SQLInvalidAuthorizationSpecException: Could not connect to address=(host=xxx.xxx.xxx)(port=3306)(type=master) : (conn=2801) Access denied for user 'metabase'@'xx.xx.xx.xx' (using password: YES)
Dec 22 06:19:48 dashboard metabase[2779]: Current charset is UTF-8. If password has been set using other charset, consider using option 'passwordCharacterEncoding'
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.util.exceptions.ExceptionFactory.createException(ExceptionFactory.java:66)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.util.exceptions.ExceptionFactory.create(ExceptionFactory.java:197)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.protocol.AbstractConnectProtocol.connectWithoutProxy(AbstractConnectProtocol.java:1394)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.util.Utils.retrieveProxy(Utils.java:635)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.MariaDbConnection.newConnection(MariaDbConnection.java:150)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.Driver.connect(Driver.java:89)
Dec 22 06:19:48 dashboard metabase[2779]: #011at java.sql/java.sql.DriverManager.getConnection(DriverManager.java:677)
Dec 22 06:19:48 dashboard metabase[2779]: #011at java.sql/java.sql.DriverManager.getConnection(DriverManager.java:189)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.data_source.DataSource.getConnection(data_source.clj:25)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:161)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:147)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager.acquireResource(C3P0PooledConnectionPool.java:202)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.resourcepool.BasicResourcePool.doAcquire(BasicResourcePool.java:1176)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.resourcepool.BasicResourcePool.doAcquireAndDecrementPendingAcquiresWithinLockOnSuccess(BasicResourcePool.java:1163)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.resourcepool.BasicResourcePool.access$700(BasicResourcePool.java:44)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.resourcepool.BasicResourcePool$ScatteredAcquireTask.run(BasicResourcePool.java:1908)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:696)
Dec 22 06:19:48 dashboard metabase[2779]: Caused by: java.sql.SQLInvalidAuthorizationSpecException: (conn=2801) Access denied for user 'metabase'@'xx.xx.xx.xx' (using password: YES)
Dec 22 06:19:48 dashboard metabase[2779]: Current charset is UTF-8. If password has been set using other charset, consider using option 'passwordCharacterEncoding'
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.util.exceptions.ExceptionFactory.createException(ExceptionFactory.java:66)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.util.exceptions.ExceptionFactory.create(ExceptionFactory.java:192)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.protocol.AbstractConnectProtocol.authenticationHandler(AbstractConnectProtocol.java:772)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.protocol.AbstractConnectProtocol.createConnection(AbstractConnectProtocol.java:558)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.protocol.AbstractConnectProtocol.connectWithoutProxy(AbstractConnectProtocol.java:1389)
Dec 22 06:19:48 dashboard metabase[2779]: #011... 14 more
Dec 22 06:19:48 dashboard metabase[2779]: 2022-12-22 06:19:48,724 INFO metabase.core :: Metabase Shutting Down ...
Dec 22 06:19:48 dashboard metabase[2779]: 2022-12-22 06:19:48,732 INFO metabase.server :: Shutting Down Embedded Jetty Webserver
Dec 22 06:19:48 dashboard metabase[2779]: 2022-12-22 06:19:48,744 INFO metabase.core :: Metabase Shutdown COMPLETE
Dec 22 06:19:52 dashboard metabase[2818]: Warning: protocol #'java-time.core/Amount is overwriting function abs
Dec 22 06:19:52 dashboard metabase[2818]: WARNING: abs already refers to: #'clojure.core/abs in namespace: java-time.core, being replaced by: #'java-time.core/abs
Dec 22 06:19:53 dashboard metabase[2818]: WARNING: abs already refers to: #'clojure.core/abs in namespace: java-time, being replaced by: #'java-time/abs
Dec 22 06:19:18 dashboard metabase[2779]: 2022-12-22 06:19:18,285 INFO metabase.core :: Setting up and migrating Metabase DB. Please sit tight, this may take a minute...
Dec 22 06:19:18 dashboard metabase[2779]: 2022-12-22 06:19:18,291 INFO db.setup :: #033[36mVerifying mysql Database Connection ...#033[0m
Dec 22 06:19:48 dashboard metabase[2779]: 2022-12-22 06:19:48,698 ERROR metabase.core :: Metabase Initialization FAILED
Dec 22 06:19:48 dashboard metabase[2779]: clojure.lang.ExceptionInfo: Unable to connect to Metabase mysql DB. {}
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.setup$fn__36344$verify_db_connection__36349$fn__36350$fn__36351.invoke(setup.clj:100)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.setup$fn__36344$verify_db_connection__36349$fn__36350.invoke(setup.clj:98)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.setup$fn__36344$verify_db_connection__36349.invoke(setup.clj:90)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.setup$fn__36426$setup_db_BANG___36431$fn__36432$fn__36435$fn__36436.invoke(setup.clj:145)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.util$do_with_us_locale.invokeStatic(util.clj:707)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.util$do_with_us_locale.invoke(util.clj:693)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.setup$fn__36426$setup_db_BANG___36431$fn__36432$fn__36435.invoke(setup.clj:144)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.setup$fn__36426$setup_db_BANG___36431$fn__36432.invoke(setup.clj:143)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.setup$fn__36426$setup_db_BANG___36431.invoke(setup.clj:137)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db$setup_db_BANG_$fn__36461.invoke(db.clj:65)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db$setup_db_BANG_.invokeStatic(db.clj:60)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db$setup_db_BANG_.invoke(db.clj:51)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.core$init_BANG__STAR_.invokeStatic(core.clj:101)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.core$init_BANG__STAR_.invoke(core.clj:87)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.core$init_BANG_.invokeStatic(core.clj:144)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.core$init_BANG_.invoke(core.clj:139)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.core$start_normally.invokeStatic(core.clj:156)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.core$start_normally.invoke(core.clj:150)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.core$_main.invokeStatic(core.clj:189)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.core$_main.doInvoke(core.clj:183)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.lang.RestFn.invoke(RestFn.java:397)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.lang.AFn.applyToHelper(AFn.java:152)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.lang.RestFn.applyTo(RestFn.java:132)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.lang.Var.applyTo(Var.java:705)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.core$apply.invokeStatic(core.clj:667)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.core$apply.invoke(core.clj:662)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.bootstrap$_main.invokeStatic(bootstrap.clj:23)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.bootstrap$_main.doInvoke(bootstrap.clj:20)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.lang.RestFn.invoke(RestFn.java:397)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.lang.AFn.applyToHelper(AFn.java:152)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.lang.RestFn.applyTo(RestFn.java:132)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.bootstrap.main(Unknown Source)
Dec 22 06:19:48 dashboard metabase[2779]: Caused by: java.sql.SQLException: Connections could not be acquired from the underlying database!
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.sql.SqlUtils.toSQLException(SqlUtils.java:118)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:692)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.c3p0.impl.AbstractPoolBackedDataSource.getConnection(AbstractPoolBackedDataSource.java:140)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.java.jdbc$get_connection.invokeStatic(jdbc.clj:372)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.java.jdbc$get_connection.invoke(jdbc.clj:274)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.java.jdbc$db_query_with_resultset_STAR_.invokeStatic(jdbc.clj:1111)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.java.jdbc$db_query_with_resultset_STAR_.invoke(jdbc.clj:1093)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.java.jdbc$query.invokeStatic(jdbc.clj:1182)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.java.jdbc$query.invoke(jdbc.clj:1144)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.java.jdbc$query.invokeStatic(jdbc.clj:1160)
Dec 22 06:19:48 dashboard metabase[2779]: #011at clojure.java.jdbc$query.invoke(jdbc.clj:1144)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.driver.sql_jdbc.connection$can_connect_with_spec_QMARK_.invokeStatic(connection.clj:297)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.driver.sql_jdbc.connection$can_connect_with_spec_QMARK_.invoke(connection.clj:294)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.setup$fn__36344$verify_db_connection__36349$fn__36350$fn__36351.invoke(setup.clj:98)
Dec 22 06:19:48 dashboard metabase[2779]: #011... 31 more
Dec 22 06:19:48 dashboard metabase[2779]: Caused by: com.mchange.v2.resourcepool.CannotAcquireResourceException: A ResourcePool could not acquire a resource from its primary factory or source.
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.resourcepool.BasicResourcePool.awaitAvailable(BasicResourcePool.java:1507)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.resourcepool.BasicResourcePool.prelimCheckoutResource(BasicResourcePool.java:644)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.resourcepool.BasicResourcePool.checkoutResource(BasicResourcePool.java:554)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutAndMarkConnectionInUse(C3P0PooledConnectionPool.java:758)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:685)
Dec 22 06:19:48 dashboard metabase[2779]: #011... 43 more
Dec 22 06:19:48 dashboard metabase[2779]: Caused by: java.sql.SQLInvalidAuthorizationSpecException: Could not connect to address=(host=xxx.xxx.xxx)(port=3306)(type=master) : (conn=2801) Access denied for user 'metabase'@'xx.xx.xx.xx' (using password: YES)
Dec 22 06:19:48 dashboard metabase[2779]: Current charset is UTF-8. If password has been set using other charset, consider using option 'passwordCharacterEncoding'
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.util.exceptions.ExceptionFactory.createException(ExceptionFactory.java:66)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.util.exceptions.ExceptionFactory.create(ExceptionFactory.java:197)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.protocol.AbstractConnectProtocol.connectWithoutProxy(AbstractConnectProtocol.java:1394)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.util.Utils.retrieveProxy(Utils.java:635)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.MariaDbConnection.newConnection(MariaDbConnection.java:150)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.Driver.connect(Driver.java:89)
Dec 22 06:19:48 dashboard metabase[2779]: #011at java.sql/java.sql.DriverManager.getConnection(DriverManager.java:677)
Dec 22 06:19:48 dashboard metabase[2779]: #011at java.sql/java.sql.DriverManager.getConnection(DriverManager.java:189)
Dec 22 06:19:48 dashboard metabase[2779]: #011at metabase.db.data_source.DataSource.getConnection(data_source.clj:25)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:161)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:147)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager.acquireResource(C3P0PooledConnectionPool.java:202)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.resourcepool.BasicResourcePool.doAcquire(BasicResourcePool.java:1176)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.resourcepool.BasicResourcePool.doAcquireAndDecrementPendingAcquiresWithinLockOnSuccess(BasicResourcePool.java:1163)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.resourcepool.BasicResourcePool.access$700(BasicResourcePool.java:44)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.resourcepool.BasicResourcePool$ScatteredAcquireTask.run(BasicResourcePool.java:1908)
Dec 22 06:19:48 dashboard metabase[2779]: #011at com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:696)
Dec 22 06:19:48 dashboard metabase[2779]: Caused by: java.sql.SQLInvalidAuthorizationSpecException: (conn=2801) Access denied for user 'metabase'@'xx.xx.xx.xx' (using password: YES)
Dec 22 06:19:48 dashboard metabase[2779]: Current charset is UTF-8. If password has been set using other charset, consider using option 'passwordCharacterEncoding'
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.util.exceptions.ExceptionFactory.createException(ExceptionFactory.java:66)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.util.exceptions.ExceptionFactory.create(ExceptionFactory.java:192)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.protocol.AbstractConnectProtocol.authenticationHandler(AbstractConnectProtocol.java:772)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.protocol.AbstractConnectProtocol.createConnection(AbstractConnectProtocol.java:558)
Dec 22 06:19:48 dashboard metabase[2779]: #011at org.mariadb.jdbc.internal.protocol.AbstractConnectProtocol.connectWithoutProxy(AbstractConnectProtocol.java:1389)
Dec 22 06:19:48 dashboard metabase[2779]: #011... 14 more
Dec 22 06:19:48 dashboard metabase[2779]: 2022-12-22 06:19:48,724 INFO metabase.core :: Metabase Shutting Down ...
Dec 22 06:19:48 dashboard metabase[2779]: 2022-12-22 06:19:48,732 INFO metabase.server :: Shutting Down Embedded Jetty Webserver
Dec 22 06:19:48 dashboard metabase[2779]: 2022-12-22 06:19:48,744 INFO metabase.core :: Metabase Shutdown COMPLETE
Dec 22 06:19:52 dashboard metabase[2818]: Warning: protocol #'java-time.core/Amount is overwriting function abs
Dec 22 06:19:52 dashboard metabase[2818]: WARNING: abs already refers to: #'clojure.core/abs in namespace: java-time.core, being replaced by: #'java-time.core/abs
Dec 22 06:19:53 dashboard metabase[2818]: WARNING: abs already refers to: #'clojure.core/abs in namespace: java-time, being replaced by: #'java-time/abs

@momentumrising The error returned from MySQL is:

Access denied for user 'metabase'@'xx.xx.xx.xx' (using password: YES)

Yes, I see that. Is there some other caveat I need to know about besides using "identified with mysql_native_password"?

@momentumrising You'll need to set the password again, when you are changing authentication method, and you need to modify the correct user.

Remember that MySQL can have a single "user", but that user can access from different hosts (or all hosts, which is written like %).
If you have multiple hosts for the same user, then make sure they are set correctly for the host that Metabase is using.

Look in your MySQL table mysql.user

So, it seems to have initialized properly now. It turns out that I had to recreate the user and make sure not to do "alter user metabase@ipaddress require ssl". Does metabase not use SSL when connecting to a mysql server? I notice that after the initial setup succeeds, then I go to add a database with some data to play with, I choose to use SSL encrypted connection, paste the server's entire certificate, (which I'm not sure why I would need to), then it tells me the following:

Could not connect to address=(host=xxx.xxx.xxx)(port=3306)(type=master) : Could not connect to xxx.xxx.xxx:3306 : PKIX path building failed: sun.security.provider.certpath.SunCertPathBuilderException: unable to find valid certification path to requested target

@momentumrising You are providing the full JDBC connection string, so if you don't tell it to use SSL, then it won't do that.
Have a look here: https://github.com/metabase/metabase/issues/12545#issuecomment-629320966

Don't confuse the Metabase application database connection with your data source connections. It's two completely different things.