"Turn model caching on'" functionality not working

Arvydas · August 26, 2022, 10:21am

I am using MySQL database type (under Admin settings -> Databases) and trying to enable "Turn model caching on". But unfortunately I was not able to achieve this and getting error message (screenshot attached). I double checked, and can confirm that all necessary rights are for this database user. I've also checked that using database tool, this user is able to create temp tables, etc.
Any ideas what might be wrong there? Is any one of you was able to enable this functionality?

Any help would be appreciated!

flamber · August 26, 2022, 10:34am

@Arvydas You need to give privileges on MySQL for the user to be able to read and write to the "database" metabase_cache_xxxxx_x as MySQL calls it schemas.

Arvydas · August 26, 2022, 10:54am

Nevertheless all the privileges are added, I am not able to turn this feature on. Shows failed and error occurred. But no more information what kind of error is that...

flamber · August 26, 2022, 11:41am

@Arvydas I cannot tell what the problem is. Look in the logs for details Admin > Troubleshooting > Logs.

troscoe · September 13, 2022, 12:39am

I ran into a similar issue. After looking at the logs as @flamber suggested. Looks like the issue is in the mysql driver. From log:
INFO metabase.driver.mysql.ddl Step :persist.check/create-schema was successful for db Learning Management System
#error {
:cause You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near 'values (1)' at line 2

github.com

metabase/metabase/blob/f2d4fd05d8afa59831772c8745b4294f2e120f0a/src/metabase/driver/mysql/ddl.clj#L103


      
          [:persist.check/create-table
           (fn create-table [conn]
             (execute-with-timeout! conn
                                    db-spec
                                    (.toMillis (t/minutes 10))
                                    [(sql.ddl/create-table-sql
                                       database
                                       {:table-name table-name
                                        :field-definitions [{:field-name "field"
                                                             :base-type :type/Text}]}
                                       "values (1)")]))
           (fn undo-create-table [conn]
             (sql.ddl/execute! conn [(sql.ddl/drop-table-sql database table-name)]))]
          [:persist.check/read-table
           (fn read-table [conn]
             (sql.ddl/jdbc-query conn [(format "select * from %s.%s"
                                               schema-name table-name)]))
           (constantly nil)]
          [:persist.check/delete-table
           (fn delete-table [conn]
             (sql.ddl/execute! conn [(sql.ddl/drop-table-sql database table-name)]))

The persist check is trying to create a table with the following:
create table persistence_check_**** as values (1)

github.com

metabase/metabase/blob/f2d4fd05d8afa59831772c8745b4294f2e120f0a/src/metabase/driver/sql/ddl.clj#L43


      
            "SQL string to drop a schema suitable"
            [{driver :engine :as database}]
            (let [q (quote-fn driver)]
              (format "drop schema if exists %s"
                      (q :table (ddl.i/schema-name database (public-settings/site-uuid))))))
          
          
(defn create-table-sql
            "Formats a create table statement within our own cache schema"
            [{driver :engine :as database} definition query]
            (let [q (quote-fn driver)]
              (format "create table %s.%s as %s"
                      (q :table (ddl.i/schema-name database (public-settings/site-uuid)))
                      (q :table (:table-name definition))
                      query)))
          
          
(defn drop-table-sql
            "Formats a drop table statement within our own cache schema"
            [{driver :engine :as database} table-name]
            (let [q (quote-fn driver)]
              (format "drop table if exists %s.%s"
                      (q :table (ddl.i/schema-name database (public-settings/site-uuid)))

Should probably work if replace values (1) with select 1 similar to the postgres driver.

Just a guess. Hopefully @flamber can confirm.

flamber · September 13, 2022, 8:33am

@troscoe What version of MySQL are you using?

Arvydas · September 13, 2022, 8:46am

And that's what I am getting in logs:

[ec5b6a0c-522a-4be8-8f02-2aa7a924520c] 2022-09-13T11:36:24+03:00 INFO metabase.driver.mysql You may need to add trustServerCertificate=true to the additional connection options to connect with SSL.
[ec5b6a0c-522a-4be8-8f02-2aa7a924520c] 2022-09-13T11:36:24+03:00 INFO metabase.driver.mysql.ddl Step :persist.check/create-schema was successful for db DWH - Cubes
[ec5b6a0c-522a-4be8-8f02-2aa7a924520c] 2022-09-13T11:36:24+03:00 WARN metabase.driver.mysql.ddl #error {
:cause You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near '(1)' at line 2
:via
[{:type java.sql.SQLSyntaxErrorException
:message (conn=3604850) You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near '(1)' at line 2
:at [org.mariadb.jdbc.internal.util.exceptions.ExceptionFactory createException ExceptionFactory.java 62]}
{:type org.mariadb.jdbc.internal.util.exceptions.MariaDbSqlException
:message You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near '(1)' at line 2
:at [org.mariadb.jdbc.internal.util.exceptions.MariaDbSqlException of MariaDbSqlException.java 34]}
{:type java.sql.SQLException
:message You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near '(1)' at line 2
:at [org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol readErrorPacket AbstractQueryProtocol.java 1695]}]
:trace
[[org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol readErrorPacket AbstractQueryProtocol.java 1695]
[org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol readPacket AbstractQueryProtocol.java 1557]
[org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol getResult AbstractQueryProtocol.java 1520]
[org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol executeQuery AbstractQueryProtocol.java 318]
[org.mariadb.jdbc.ClientSidePreparedStatement executeInternal ClientSidePreparedStatement.java 220]
[org.mariadb.jdbc.ClientSidePreparedStatement execute ClientSidePreparedStatement.java 149]
[org.mariadb.jdbc.ClientSidePreparedStatement executeQuery ClientSidePreparedStatement.java 163]
[com.mchange.v2.c3p0.impl.NewProxyPreparedStatement executeQuery NewProxyPreparedStatement.java 1471]
[clojure.java.jdbc$execute_query_with_params invokeStatic jdbc.clj 1090]
[clojure.java.jdbc$execute_query_with_params invoke jdbc.clj 1084]
[clojure.java.jdbc$db_query_with_resultset_STAR_ invokeStatic jdbc.clj 1106]
[clojure.java.jdbc$db_query_with_resultset_STAR_ invoke jdbc.clj 1093]
[clojure.java.jdbc$query invokeStatic jdbc.clj 1182]
[clojure.java.jdbc$query invoke jdbc.clj 1144]
[clojure.java.jdbc$query invokeStatic jdbc.clj 1160]
[clojure.java.jdbc$query invoke jdbc.clj 1144]
[metabase.driver.sql.ddl$jdbc_query invokeStatic ddl.clj 23]
[metabase.driver.sql.ddl$jdbc_query invoke ddl.clj 20]
[metabase.driver.mysql.ddl$exec_async$fn__82806$fn__82810 invoke ddl.clj 21]
[metabase.driver.mysql.ddl$exec_async$fn__82806 invoke ddl.clj 17]
[clojure.core.async$thread_call$fn__16312 invoke async.clj 484]
[clojure.lang.AFn run AFn.java 22]
[java.util.concurrent.ThreadPoolExecutor runWorker nil -1]
[java.util.concurrent.ThreadPoolExecutor$Worker run nil -1]
[java.lang.Thread run nil -1]]}
[ec5b6a0c-522a-4be8-8f02-2aa7a924520c] 2022-09-13T11:36:24+03:00 INFO metabase.driver.mysql.ddl Step :persist.check/create-table was successful for db DWH - Cubes
[ec5b6a0c-522a-4be8-8f02-2aa7a924520c] 2022-09-13T11:36:24+03:00 WARN metabase.driver.mysql.ddl Error in :persist.check/read-table while checking for model persistence permissions.
[ec5b6a0c-522a-4be8-8f02-2aa7a924520c] 2022-09-13T11:36:24+03:00 WARN metabase.driver.mysql.ddl #error {
:cause Table 'metabase_cache_a5484_2.persistence_check_9227' doesn't exist
:via
[{:type java.sql.SQLSyntaxErrorException
:message (conn=3604849) Table 'metabase_cache_a5484_2.persistence_check_9227' doesn't exist
:at [org.mariadb.jdbc.internal.util.exceptions.ExceptionFactory createException ExceptionFactory.java 62]}
{:type org.mariadb.jdbc.internal.util.exceptions.MariaDbSqlException
:message Table 'metabase_cache_a5484_2.persistence_check_9227' doesn't exist
:at [org.mariadb.jdbc.internal.util.exceptions.MariaDbSqlException of MariaDbSqlException.java 34]}
{:type java.sql.SQLException
:message Table 'metabase_cache_a5484_2.persistence_check_9227' doesn't exist
:at [org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol readErrorPacket AbstractQueryProtocol.java 1695]}]
:trace
[[org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol readErrorPacket AbstractQueryProtocol.java 1695]
[org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol readPacket AbstractQueryProtocol.java 1557]
[org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol getResult AbstractQueryProtocol.java 1520]
[org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol executeQuery AbstractQueryProtocol.java 318]
[org.mariadb.jdbc.ClientSidePreparedStatement executeInternal ClientSidePreparedStatement.java 220]
[org.mariadb.jdbc.ClientSidePreparedStatement execute ClientSidePreparedStatement.java 149]
[org.mariadb.jdbc.ClientSidePreparedStatement executeQuery ClientSidePreparedStatement.java 163]
[com.mchange.v2.c3p0.impl.NewProxyPreparedStatement executeQuery NewProxyPreparedStatement.java 1471]
[clojure.java.jdbc$execute_query_with_params invokeStatic jdbc.clj 1090]
[clojure.java.jdbc$execute_query_with_params invoke jdbc.clj 1084]
[clojure.java.jdbc$db_query_with_resultset_STAR_ invokeStatic jdbc.clj 1106]
[clojure.java.jdbc$db_query_with_resultset_STAR_ invoke jdbc.clj 1093]
[clojure.java.jdbc$query invokeStatic jdbc.clj 1182]
[clojure.java.jdbc$query invoke jdbc.clj 1144]
[clojure.java.jdbc$query invokeStatic jdbc.clj 1160]
[clojure.java.jdbc$query invoke jdbc.clj 1144]
[metabase.driver.sql.ddl$jdbc_query invokeStatic ddl.clj 23]
[metabase.driver.sql.ddl$jdbc_query invoke ddl.clj 20]
[metabase.driver.mysql.ddl$fn__82840$read_table__82850 invoke ddl.clj 108]
[metabase.driver.mysql.ddl$fn__82840$fn__82914 invoke ddl.clj 121]
[metabase.driver.mysql.ddl$fn__82840 invokeStatic ddl.clj 121]
[metabase.driver.mysql.ddl$fn__82840 invoke ddl.clj 78]
[clojure.lang.MultiFn invoke MultiFn.java 229]
[metabase.api.database$fn__76513 invokeStatic database.clj 691]
[metabase.api.database$fn__76513 invoke database.clj 679]
[compojure.core$wrap_response$fn__28102 invoke core.clj 160]
[compojure.core$wrap_route_middleware$fn__28086 invoke core.clj 132]
[compojure.core$wrap_route_info$fn__28091 invoke core.clj 139]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 151]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 153]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 153]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 153]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 152]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 153]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 152]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 153]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 152]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 152]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 152]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 153]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 153]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 152]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 152]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114 invoke core.clj 200]
[metabase.server.middleware.auth$enforce_authentication$fn__68926 invoke auth.clj 14]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114 invoke core.clj 200]
[compojure.core$make_context$handler__28142 invoke core.clj 289]
[compojure.core$make_context$fn__28146 invoke core.clj 299]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$make_context$fn__28146 invoke core.clj 300]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$make_context$fn__28146 invoke core.clj 300]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$make_context$fn__28146 invoke core.clj 300]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$make_context$fn__28146 invoke core.clj 300]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$make_context$fn__28146 invoke core.clj 300]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$make_context$fn__28146 invoke core.clj 300]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$make_context$fn__28146 invoke core.clj 300]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[metabase.api.routes$fn__79912$fn__79915 invoke routes.clj 60]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114 invoke core.clj 200]
[clojure.lang.AFn applyToHelper AFn.java 160]
[clojure.lang.AFn applyTo AFn.java 144]
[clojure.core$apply invokeStatic core.clj 667]
[clojure.core$apply invoke core.clj 662]
[metabase.server.routes$fn__80067$fn__80068 doInvoke routes.clj 67]
[clojure.lang.RestFn invoke RestFn.java 436]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114 invoke core.clj 200]
[compojure.core$make_context$handler__28142 invoke core.clj 289]
[compojure.core$make_context$fn__28146 invoke core.clj 299]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 153]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 153]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[compojure.core$wrap_route_matches$fn__28095 invoke core.clj 153]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke core.clj 197]
[metabase.server.routes$fn__80053$fn__80055 invoke routes.clj 45]
[compojure.core$routes$fn__28114$f__28115 invoke core.clj 198]
[compojure.core$routes$fn__28114 invoke core.clj 200]
[metabase.server.middleware.exceptions$catch_uncaught_exceptions$fn__76804 invoke exceptions.clj 98]
[metabase.server.middleware.exceptions$catch_api_exceptions$fn__76801 invoke exceptions.clj 86]
[metabase.server.middleware.log$log_api_call$fn__80518$fn__80519$fn__80520 invoke log.clj 211]
[metabase.driver.sql_jdbc.execute.diagnostic$do_with_diagnostic_info invokeStatic diagnostic.clj 15]
[metabase.driver.sql_jdbc.execute.diagnostic$do_with_diagnostic_info invoke diagnostic.clj 9]
[metabase.server.middleware.log$log_api_call$fn__80518$fn__80519 invoke log.clj 203]
[toucan.db$_do_with_call_counting invokeStatic db.clj 236]
[toucan.db$_do_with_call_counting invoke db.clj 229]
[metabase.server.middleware.log$log_api_call$fn__80518 invoke log.clj 202]
[metabase.server.middleware.browser_cookie$ensure_browser_id_cookie$fn__83597 invoke browser_cookie.clj 38]
[metabase.server.middleware.security$add_security_headers$fn__62333 invoke security.clj 149]
[metabase.server.middleware.json$wrap_json_body$fn__82554 invoke json.clj 63]
[metabase.server.middleware.json$wrap_streamed_json_response$fn__82572 invoke json.clj 99]
[metabase.server.middleware.offset_paging$handle_paging$fn__62357 invoke offset_paging.clj 42]
[ring.middleware.keyword_params$wrap_keyword_params$fn__83864 invoke keyword_params.clj 55]
[ring.middleware.params$wrap_params$fn__83883 invoke params.clj 77]
[metabase.server.middleware.misc$maybe_set_site_url$fn__35776 invoke misc.clj 59]
[metabase.server.middleware.session$reset_session_timeout$fn__47465 invoke session.clj 377]
[metabase.server.middleware.session$bind_current_user$fn__47448$fn__47449 invoke session.clj 313]
[metabase.server.middleware.session$do_with_current_user invokeStatic session.clj 292]
[metabase.server.middleware.session$do_with_current_user invoke session.clj 281]
[metabase.server.middleware.session$bind_current_user$fn__47448 invoke session.clj 312]
[metabase.server.middleware.session$wrap_current_user_info$fn__47430 invoke session.clj 262]
[metabase.server.middleware.session$wrap_session_id$fn__47414 invoke session.clj 195]
[metabase.server.middleware.auth$wrap_api_key$fn__68934 invoke auth.clj 27]
[ring.middleware.cookies$wrap_cookies$fn__83784 invoke cookies.clj 216]
[metabase.server.middleware.misc$add_content_type$fn__35759 invoke misc.clj 27]
[metabase.server.middleware.misc$disable_streaming_buffering$fn__35784 invoke misc.clj 76]
[ring.middleware.gzip$wrap_gzip$fn__83826 invoke gzip.clj 86]
[metabase.server.middleware.misc$bind_request$fn__35787 invoke misc.clj 93]
[metabase.server.middleware.ssl$redirect_to_https_middleware$fn__83613 invoke ssl.clj 38]
[metabase.server$async_proxy_handler$fn__80290 invoke server.clj 73]
[metabase.server.proxy$org.eclipse.jetty.server.handler.AbstractHandler$ff19274a handle nil -1]
[org.eclipse.jetty.server.handler.HandlerWrapper handle HandlerWrapper.java 127]
[org.eclipse.jetty.server.Server handle Server.java 516]
[org.eclipse.jetty.server.HttpChannel lambda$handle$1 HttpChannel.java 487]
[org.eclipse.jetty.server.HttpChannel dispatch HttpChannel.java 732]
[org.eclipse.jetty.server.HttpChannel handle HttpChannel.java 479]
[org.eclipse.jetty.server.HttpConnection onFillable HttpConnection.java 277]
[org.eclipse.jetty.io.AbstractConnection$ReadCallback succeeded AbstractConnection.java 311]
[org.eclipse.jetty.io.FillInterest fillable FillInterest.java 105]
[org.eclipse.jetty.io.ChannelEndPoint$1 run ChannelEndPoint.java 104]
[org.eclipse.jetty.util.thread.strategy.EatWhatYouKill runTask EatWhatYouKill.java 338]
[org.eclipse.jetty.util.thread.strategy.EatWhatYouKill doProduce EatWhatYouKill.java 315]
[org.eclipse.jetty.util.thread.strategy.EatWhatYouKill tryProduce EatWhatYouKill.java 173]
[org.eclipse.jetty.util.thread.strategy.EatWhatYouKill run EatWhatYouKill.java 131]
[org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread run ReservedThreadExecutor.java 409]
[org.eclipse.jetty.util.thread.QueuedThreadPool runJob QueuedThreadPool.java 883]
[org.eclipse.jetty.util.thread.QueuedThreadPool$Runner run QueuedThreadPool.java 1034]
[java.lang.Thread run nil -1]]}
[ec5b6a0c-522a-4be8-8f02-2aa7a924520c] 2022-09-13T11:36:24+03:00 WARN metabase.driver.mysql.ddl Undoing step :persist.check/create-table for db DWH - Cubes
[ec5b6a0c-522a-4be8-8f02-2aa7a924520c] 2022-09-13T11:36:24+03:00 WARN metabase.driver.mysql.ddl Undoing step :persist.check/create-schema for db DWH - Cubes
[ec5b6a0c-522a-4be8-8f02-2aa7a924520c] 2022-09-13T11:36:24+03:00 ERROR metabase.server.middleware.log POST /api/database/2/persist 500 69.0 ms (3 DB calls)
{:via
[{:type clojure.lang.ExceptionInfo,
:message "Lack permission to read table in schema metabase_cache_a5484_2",
:data {:error :persist.check/read-table, :database "DWH - Cubes"},
:at [metabase.api.database$fn__76513 invokeStatic "database.clj" 701]}],
:trace
[[metabase.api.database$fn__76513 invokeStatic "database.clj" 701]
[metabase.api.database$fn__76513 invoke "database.clj" 679]
[compojure.core$wrap_response$fn__28102 invoke "core.clj" 160]
[compojure.core$wrap_route_middleware$fn__28086 invoke "core.clj" 132]
[compojure.core$wrap_route_info$fn__28091 invoke "core.clj" 139]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 151]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 153]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 153]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 153]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 152]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 153]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 152]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 153]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 152]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 152]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 152]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 153]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 153]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 152]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 152]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114 invoke "core.clj" 200]
[metabase.server.middleware.auth$enforce_authentication$fn__68926 invoke "auth.clj" 14]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114 invoke "core.clj" 200]
[compojure.core$make_context$handler__28142 invoke "core.clj" 289]
[compojure.core$make_context$fn__28146 invoke "core.clj" 299]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$make_context$fn__28146 invoke "core.clj" 300]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$make_context$fn__28146 invoke "core.clj" 300]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$make_context$fn__28146 invoke "core.clj" 300]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$make_context$fn__28146 invoke "core.clj" 300]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$make_context$fn__28146 invoke "core.clj" 300]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$make_context$fn__28146 invoke "core.clj" 300]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$make_context$fn__28146 invoke "core.clj" 300]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[metabase.api.routes$fn__79912$fn__79915 invoke "routes.clj" 60]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114 invoke "core.clj" 200]
[clojure.lang.AFn applyToHelper "AFn.java" 160]
[clojure.lang.AFn applyTo "AFn.java" 144]
[clojure.core$apply invokeStatic "core.clj" 667]
[clojure.core$apply invoke "core.clj" 662]
[metabase.server.routes$fn__80067$fn__80068 doInvoke "routes.clj" 67]
[clojure.lang.RestFn invoke "RestFn.java" 436]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114 invoke "core.clj" 200]
[compojure.core$make_context$handler__28142 invoke "core.clj" 289]
[compojure.core$make_context$fn__28146 invoke "core.clj" 299]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 153]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 153]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[compojure.core$wrap_route_matches$fn__28095 invoke "core.clj" 153]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114$f__28115$respond_SINGLEQUOTE___28116 invoke "core.clj" 197]
[metabase.server.routes$fn__80053$fn__80055 invoke "routes.clj" 45]
[compojure.core$routes$fn__28114$f__28115 invoke "core.clj" 198]
[compojure.core$routes$fn__28114 invoke "core.clj" 200]
[metabase.server.middleware.exceptions$catch_uncaught_exceptions$fn__76804 invoke "exceptions.clj" 98]
[metabase.server.middleware.exceptions$catch_api_exceptions$fn__76801 invoke "exceptions.clj" 86]
[metabase.server.middleware.log$log_api_call$fn__80518$fn__80519$fn__80520 invoke "log.clj" 211]
[metabase.driver.sql_jdbc.execute.diagnostic$do_with_diagnostic_info invokeStatic "diagnostic.clj" 15]
[metabase.driver.sql_jdbc.execute.diagnostic$do_with_diagnostic_info invoke "diagnostic.clj" 9]
[metabase.server.middleware.log$log_api_call$fn__80518$fn__80519 invoke "log.clj" 203]
[toucan.db$_do_with_call_counting invokeStatic "db.clj" 236]
[toucan.db$_do_with_call_counting invoke "db.clj" 229]
[metabase.server.middleware.log$log_api_call$fn__80518 invoke "log.clj" 202]
[metabase.server.middleware.browser_cookie$ensure_browser_id_cookie$fn__83597 invoke "browser_cookie.clj" 38]
[metabase.server.middleware.security$add_security_headers$fn__62333 invoke "security.clj" 149]
[metabase.server.middleware.json$wrap_json_body$fn__82554 invoke "json.clj" 63]
[metabase.server.middleware.json$wrap_streamed_json_response$fn__82572 invoke "json.clj" 99]
[metabase.server.middleware.offset_paging$handle_paging$fn__62357 invoke "offset_paging.clj" 42]
[ring.middleware.keyword_params$wrap_keyword_params$fn__83864 invoke "keyword_params.clj" 55]
[ring.middleware.params$wrap_params$fn__83883 invoke "params.clj" 77]
[metabase.server.middleware.misc$maybe_set_site_url$fn__35776 invoke "misc.clj" 59]
[metabase.server.middleware.session$reset_session_timeout$fn__47465 invoke "session.clj" 377]
[metabase.server.middleware.session$bind_current_user$fn__47448$fn__47449 invoke "session.clj" 313]
[metabase.server.middleware.session$do_with_current_user invokeStatic "session.clj" 292]
[metabase.server.middleware.session$do_with_current_user invoke "session.clj" 281]
[metabase.server.middleware.session$bind_current_user$fn__47448 invoke "session.clj" 312]
[metabase.server.middleware.session$wrap_current_user_info$fn__47430 invoke "session.clj" 262]
[metabase.server.middleware.session$wrap_session_id$fn__47414 invoke "session.clj" 195]
[metabase.server.middleware.auth$wrap_api_key$fn__68934 invoke "auth.clj" 27]
[ring.middleware.cookies$wrap_cookies$fn__83784 invoke "cookies.clj" 216]
[metabase.server.middleware.misc$add_content_type$fn__35759 invoke "misc.clj" 27]
[metabase.server.middleware.misc$disable_streaming_buffering$fn__35784 invoke "misc.clj" 76]
[ring.middleware.gzip$wrap_gzip$fn__83826 invoke "gzip.clj" 86]
[metabase.server.middleware.misc$bind_request$fn__35787 invoke "misc.clj" 93]
[metabase.server.middleware.ssl$redirect_to_https_middleware$fn__83613 invoke "ssl.clj" 38]
[metabase.server$async_proxy_handler$fn__80290 invoke "server.clj" 73]
[metabase.server.proxy$org.eclipse.jetty.server.handler.AbstractHandler$ff19274a handle nil -1]
[org.eclipse.jetty.server.handler.HandlerWrapper handle "HandlerWrapper.java" 127]
[org.eclipse.jetty.server.Server handle "Server.java" 516]
[org.eclipse.jetty.server.HttpChannel lambda$handle$1 "HttpChannel.java" 487]
[org.eclipse.jetty.server.HttpChannel dispatch "HttpChannel.java" 732]
[org.eclipse.jetty.server.HttpChannel handle "HttpChannel.java" 479]
[org.eclipse.jetty.server.HttpConnection onFillable "HttpConnection.java" 277]
[org.eclipse.jetty.io.AbstractConnection$ReadCallback succeeded "AbstractConnection.java" 311]
[org.eclipse.jetty.io.FillInterest fillable "FillInterest.java" 105]
[org.eclipse.jetty.io.ChannelEndPoint$1 run "ChannelEndPoint.java" 104]
[org.eclipse.jetty.util.thread.strategy.EatWhatYouKill runTask "EatWhatYouKill.java" 338]
[org.eclipse.jetty.util.thread.strategy.EatWhatYouKill doProduce "EatWhatYouKill.java" 315]
[org.eclipse.jetty.util.thread.strategy.EatWhatYouKill tryProduce "EatWhatYouKill.java" 173]
[org.eclipse.jetty.util.thread.strategy.EatWhatYouKill run "EatWhatYouKill.java" 131]
[org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread run "ReservedThreadExecutor.java" 409]
[org.eclipse.jetty.util.thread.QueuedThreadPool runJob "QueuedThreadPool.java" 883]
[org.eclipse.jetty.util.thread.QueuedThreadPool$Runner run "QueuedThreadPool.java" 1034]
[java.lang.Thread run nil -1]],
:cause "Lack permission to read table in schema metabase_cache_a5484_2",
:data {:error :persist.check/read-table, :database "DWH - Cubes"},
:message "Lack permission to read table in schema metabase_cache_a5484_2",
:error :persist.check/read-table,
:database "DWH - Cubes"}

troscoe · September 13, 2022, 1:46pm

5.7 running on AWS Aurora.

JackL · September 18, 2022, 10:38pm

Jumping in as another user with the same issue - log dump looks almost identical to the ones posted, though I am running on a slightly strange setup with the metabase cache being MySQL 5.7 but the database we're pulling data from is 8.0. Metabase v0.44.2.

troscoe · September 21, 2022, 3:56am

@flamber is 5.7 supported? Could look into adding a pull request and adding some tests if the check is the primary concern. Either that and/or open a GitHub issue to track if plans are to support it.

flamber · September 21, 2022, 4:37pm

I've created an issue for this:
https://github.com/metabase/metabase/issues/25552 - upvote by clicking on the first post