Hello,
I've configured Metabase to cache my models every 12 hours. However, upon checking the Tools tab, I noticed that the last caching timestamp for the model ranges from 17 hours ago to a month ago. I'm puzzled by this significant difference, and I'm wondering why my models aren't refreshing as expected.
All models are in Queued status.
I restarted the Metabase service.
The settings under Setting > Caching > Models are enabled and set to refresh every 12 hours. Additionally, in the Database settings, the option to turn on model caching is also enabled.
I would appreciate any insights or assistance in resolving this issue.
{
"browser-info": {
"language": "fr-FR",
"platform": "Win32",
"userAgent": "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36",
"vendor": "Google Inc."
},
"system-info": {
"file.encoding": "Cp1252",
"java.runtime.name": "OpenJDK Runtime Environment",
"java.runtime.version": "17.0.4.1+1",
"java.vendor": "Eclipse Adoptium",
"java.vendor.url": "https://adoptium.net/",
"java.version": "17.0.4.1",
"java.vm.name": "OpenJDK 64-Bit Server VM",
"java.vm.version": "17.0.4.1+1",
"os.name": "Windows Server 2016",
"os.version": "10.0",
"user.language": "fr",
"user.timezone": "Europe/Paris"
},
"metabase-info": {
"databases": [
"postgres",
"mysql"
],
"hosting-env": "unknown",
"application-database": "mysql",
"application-database-details": {
"database": {
"name": "MySQL",
"version": "5.7.36"
},
"jdbc-driver": {
"name": "MariaDB Connector/J",
"version": "2.7.6"
}
},
"run-mode": "prod",
"version": {
"date": "2023-11-07",
"tag": "v0.47.7",
"branch": "?",
"hash": "dd51fd4"
},
"settings": {
"report-timezone": null
}
}
}
Logs :
[1ec5bbef-0057-4636-8854-568276bc71bb] 2023-11-14T21:37:58+01:00 ERROR metabase.models.field-values Error fetching field values
java.sql.SQLException: Connections could not be acquired from the underlying database!
at com.mchange.v2.sql.SqlUtils.toSQLException(SqlUtils.java:118)
at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:692)
at com.mchange.v2.c3p0.impl.AbstractPoolBackedDataSource.getConnection(AbstractPoolBackedDataSource.java:140)
at metabase.driver.sql_jdbc.execute$fn__80829$do_with_resolved_connection__80830.invoke(execute.clj:332)
at clojure.lang.AFn.applyToHelper(AFn.java:165)
at clojure.lang.AFn.applyTo(AFn.java:144)
at clojure.core$apply.invokeStatic(core.clj:667)
at clojure.core$apply.invoke(core.clj:662)
at malli.core$instrument$fn__13254.doInvoke(core.cljc:2526)
at clojure.lang.RestFn.invoke(RestFn.java:457)
at metabase.driver.sql_jdbc.execute$fn__80861.invokeStatic(execute.clj:380)
at metabase.driver.sql_jdbc.execute$fn__80861.invoke(execute.clj:378)
at clojure.lang.MultiFn.invoke(MultiFn.java:244)
at metabase.driver.sql_jdbc.execute$execute_reducible_query.invokeStatic(execute.clj:674)
at metabase.driver.sql_jdbc.execute$execute_reducible_query.invoke(execute.clj:663)
at metabase.driver.sql_jdbc.execute$execute_reducible_query.invokeStatic(execute.clj:671)
at metabase.driver.sql_jdbc.execute$execute_reducible_query.invoke(execute.clj:663)
at metabase.driver.sql_jdbc$fn__115960.invokeStatic(sql_jdbc.clj:72)
at metabase.driver.sql_jdbc$fn__115960.invoke(sql_jdbc.clj:70)
at clojure.lang.MultiFn.invoke(MultiFn.java:244)
at metabase.query_processor.context$executef.invokeStatic(context.clj:60)
at metabase.query_processor.context$executef.invoke(context.clj:49)
at metabase.query_processor.context.default$default_runf.invokeStatic(default.clj:68)
at metabase.query_processor.context.default$default_runf.invoke(default.clj:66)
at metabase.query_processor.context$runf.invokeStatic(context.clj:46)
at metabase.query_processor.context$runf.invoke(context.clj:40)
at metabase.query_processor.reducible$identity_qp.invokeStatic(reducible.clj:15)
at metabase.query_processor.reducible$identity_qp.invoke(reducible.clj:12)
at metabase.query_processor.middleware.cache$maybe_return_cached_results$maybe_return_cached_results_STAR___77088.invoke(cache.clj:224)
at metabase.query_processor.middleware.permissions$check_query_permissions$fn__72671.invoke(permissions.clj:126)
at metabase.query_processor.middleware.mbql_to_native$mbql__GT_native$fn__76213.invoke(mbql_to_native.clj:24)
at metabase.query_processor$fn__78321$combined_post_process__78326$combined_post_process_STAR___78327.invoke(query_processor.clj:260)
at metabase.query_processor$fn__78321$combined_pre_process__78322$combined_pre_process_STAR___78323.invoke(query_processor.clj:257)
at metabase.query_processor.middleware.resolve_database_and_driver$resolve_database_and_driver$fn__76999$fn__77004.invoke(resolve_database_and_driver.clj:36)
at metabase.driver$do_with_driver.invokeStatic(driver.clj:93)
at metabase.driver$do_with_driver.invoke(driver.clj:88)
at metabase.query_processor.middleware.resolve_database_and_driver$resolve_database_and_driver$fn__76999.invoke(resolve_database_and_driver.clj:35)
at metabase.query_processor.middleware.fetch_source_query$resolve_card_id_source_tables$fn__72981.invoke(fetch_source_query.clj:316)
at metabase.query_processor.middleware.store$initialize_store$fn__73162$fn__73163.invoke(store.clj:12)
at metabase.query_processor.store$do_with_store.invokeStatic(store.clj:56)
at metabase.query_processor.store$do_with_store.invoke(store.clj:50)
at metabase.query_processor.middleware.store$initialize_store$fn__73162.invoke(store.clj:11)
at metabase.query_processor.middleware.normalize_query$normalize$fn__77295.invoke(normalize_query.clj:36)
at metabase.query_processor.reducible$async_qp$qp_STAR___66815$thunk__66817.invoke(reducible.clj:103)
at metabase.query_processor.reducible$async_qp$qp_STAR___66815.invoke(reducible.clj:109)
at metabase.query_processor.reducible$async_qp$qp_STAR___66815.invoke(reducible.clj:94)
at metabase.query_processor.reducible$async_qp$qp_STAR___66815.invoke(reducible.clj:91)
at clojure.lang.AFn.applyToHelper(AFn.java:154)
at clojure.lang.AFn.applyTo(AFn.java:144)
at clojure.core$apply.invokeStatic(core.clj:667)
at clojure.core$apply.invoke(core.clj:662)
at metabase.query_processor.reducible$sync_qp$qp_STAR___66827.doInvoke(reducible.clj:129)
at clojure.lang.RestFn.applyTo(RestFn.java:137)
at clojure.core$apply.invokeStatic(core.clj:669)
at clojure.core$apply.invoke(core.clj:662)
at metabase.query_processor$process_query.invokeStatic(query_processor.clj:295)
at metabase.query_processor$process_query.doInvoke(query_processor.clj:289)
at clojure.lang.RestFn.invoke(RestFn.java:410)
at metabase.db.metadata_queries$qp_query$fn__88312.invoke(metadata_queries.clj:23)
at metabase.db.metadata_queries$qp_query.invokeStatic(metadata_queries.clj:22)
at metabase.db.metadata_queries$qp_query.invoke(metadata_queries.clj:20)
at metabase.db.metadata_queries$field_query.invokeStatic(metadata_queries.clj:33)
at metabase.db.metadata_queries$field_query.invoke(metadata_queries.clj:31)
at metabase.db.metadata_queries$fn__88329$field_distinct_values__88338$fn__88341.invoke(metadata_queries.clj:65)
at metabase.db.metadata_queries$fn__88329$field_distinct_values__88338.invoke(metadata_queries.clj:58)
at metabase.db.metadata_queries$fn__88329$field_distinct_values__88338$fn__88339.invoke(metadata_queries.clj:62)
at metabase.db.metadata_queries$fn__88329$field_distinct_values__88338.invoke(metadata_queries.clj:58)
at clojure.lang.Var.invoke(Var.java:384)
at metabase.models.field_values$distinct_values.invokeStatic(field_values.clj:318)
at metabase.models.field_values$distinct_values.invoke(field_values.clj:303)
at metabase.models.field_values$create_or_update_full_field_values_BANG.invokeStatic(field_values.clj:345)
at metabase.models.field_values$create_or_update_full_field_values_BANG_.doInvoke(field_values.clj:337)
at clojure.lang.RestFn.invoke(RestFn.java:410)
at metabase.sync.field_values$fn__98689$update_field_values_for_field_BANG___98694$fn__98695.invoke(field_values.clj:31)
at metabase.sync.field_values$fn__98689$update_field_values_for_field_BANG___98694.invoke(field_values.clj:25)
at metabase.sync.field_values$fn__98720$update_field_values_for_table_BANG___98725$fn__98726$fn__98727$fn__98728.invoke(field_values.clj:56)
at metabase.sync.util$do_with_error_handling.invokeStatic(util.clj:162)
at metabase.sync.util$do_with_error_handling.invoke(util.clj:155)
at metabase.sync.field_values$fn__98720$update_field_values_for_table_BANG___98725$fn__98726$fn__98727.invoke(field_values.clj:54)
at clojure.lang.PersistentVector.reduce(PersistentVector.java:343)
at clojure.core$reduce.invokeStatic(core.clj:6885)
at clojure.core$reduce.invoke(core.clj:6868)
at metabase.sync.field_values$fn__98720$update_field_values_for_table_BANG___98725$fn__98726.invoke(field_values.clj:53)
at metabase.sync.field_values$fn__98720$update_field_values_for_table_BANG___98725.invoke(field_values.clj:50)
at clojure.core$map$fn__5935.invoke(core.clj:2770)
at clojure.lang.LazySeq.sval(LazySeq.java:42)
at clojure.lang.LazySeq.seq(LazySeq.java:51)
at clojure.lang.ChunkedCons.chunkedNext(ChunkedCons.java:59)
at clojure.core$chunk_next.invokeStatic(core.clj:710)
at clojure.core$reduce1.invokeStatic(core.clj:944)
at clojure.core$reduce1.invokeStatic(core.clj:936)
at clojure.core$merge_with.invokeStatic(core.clj:3076)
at clojure.core$merge_with.doInvoke(core.clj:3068)
at clojure.lang.RestFn.applyTo(RestFn.java:139)
at clojure.core$apply.invokeStatic(core.clj:669)
at clojure.core$apply.invoke(core.clj:662)
at metabase.sync.field_values$fn__98749$update_field_values_for_database_BANG___98754$fn__98755.invoke(field_values.clj:65)
at metabase.sync.field_values$fn__98749$update_field_values_for_database_BANG___98754.invoke(field_values.clj:62)
at metabase.sync.field_values$make_sync_field_values_steps$fn__98831.invoke(field_values.clj:114)
at clojure.lang.AFn.applyToHelper(AFn.java:154)
at clojure.lang.AFn.applyTo(AFn.java:144)
at clojure.core$apply.invokeStatic(core.clj:669)
at clojure.core$apply.invoke(core.clj:662)
at metabase.sync.util$fn__62959$run_step_with_metadata__62964$fn__62968$fn__62970.doInvoke(util.clj:380)
at clojure.lang.RestFn.invoke(RestFn.java:397)
at metabase.sync.util$with_start_and_finish_logging_STAR_.invokeStatic(util.clj:104)
at metabase.sync.util$with_start_and_finish_logging_STAR_.invoke(util.clj:98)
at metabase.sync.util$with_start_and_finish_debug_logging.invokeStatic(util.clj:121)
at metabase.sync.util$with_start_and_finish_debug_logging.invoke(util.clj:118)
at metabase.sync.util$fn__62959$run_step_with_metadata__62964$fn__62968.invoke(util.clj:375)
at metabase.sync.util$fn__62959$run_step_with_metadata__62964.invoke(util.clj:370)
at metabase.sync.util$fn__63186$run_sync_operation__63191$fn__63192$fn__63200.invoke(util.clj:486)
at metabase.sync.util$fn__63186$run_sync_operation__63191$fn__63192.invoke(util.clj:484)
at metabase.sync.util$fn__63186$run_sync_operation__63191.invoke(util.clj:478)
at metabase.sync.field_values$fn__98840$update_field_values_BANG___98845$fn__98846$fn__98847.invoke(field_values.clj:124)
at metabase.sync.util$do_with_error_handling.invokeStatic(util.clj:162)
at metabase.sync.util$do_with_error_handling.invoke(util.clj:155)
at clojure.core$partial$fn__5910.invoke(core.clj:2647)
at metabase.driver$fn__48152.invokeStatic(driver.clj:717)
at metabase.driver$fn__48152.invoke(driver.clj:717)
at clojure.lang.MultiFn.invoke(MultiFn.java:239)
at metabase.sync.util$sync_in_context$fn__62879.invoke(util.clj:140)
at metabase.sync.util$with_db_logging_disabled$fn__62876.invoke(util.clj:131)
at metabase.sync.util$with_start_and_finish_logging_STAR_.invokeStatic(util.clj:104)
at metabase.sync.util$with_start_and_finish_logging_STAR_.invoke(util.clj:98)
at metabase.sync.util$with_start_and_finish_logging$fn__62863.invoke(util.clj:116)
at metabase.sync.util$with_sync_events$fn__62858.invoke(util.clj:90)
at metabase.sync.util$with_duplicate_ops_prevented$fn__62848.invoke(util.clj:69)
at metabase.sync.util$do_sync_operation.invokeStatic(util.clj:183)
at metabase.sync.util$do_sync_operation.invoke(util.clj:180)
at metabase.sync.field_values$fn__98840$update_field_values_BANG___98845$fn__98846.invoke(field_values.clj:121)
at metabase.sync.field_values$fn__98840$update_field_values_BANG___98845.invoke(field_values.clj:117)
at metabase.task.sync_databases$update_field_values_BANG_.invokeStatic(sync_databases.clj:97)
at metabase.task.sync_databases$update_field_values_BANG_.invoke(sync_databases.clj:87)
at metabase.task.sync_databases.UpdateFieldValues.execute(sync_databases.clj:103)
at org.quartz.core.JobRunShell.run(JobRunShell.java:202)
at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573)
Caused by: com.mchange.v2.resourcepool.CannotAcquireResourceException: A ResourcePool could not acquire a resource from its primary factory or source.
at com.mchange.v2.resourcepool.BasicResourcePool.awaitAvailable(BasicResourcePool.java:1507)
at com.mchange.v2.resourcepool.BasicResourcePool.prelimCheckoutResource(BasicResourcePool.java:644)
at com.mchange.v2.resourcepool.BasicResourcePool.checkoutResource(BasicResourcePool.java:554)
at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutAndMarkConnectionInUse(C3P0PooledConnectionPool.java:758)
at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:685)
... 135 more
Caused by: org.postgresql.util.PSQLException: La tentative de connexion a échoué.
at org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl(ConnectionFactoryImpl.java:354)
at org.postgresql.core.ConnectionFactory.openConnection(ConnectionFactory.java:54)
at org.postgresql.jdbc.PgConnection.(PgConnection.java:253)
at org.postgresql.Driver.makeConnection(Driver.java:434)
at org.postgresql.Driver.connect(Driver.java:291)
at java.sql/java.sql.DriverManager.getConnection(DriverManager.java:681)
at java.sql/java.sql.DriverManager.getConnection(DriverManager.java:190)
at metabase.connection_pool$proxy_data_source$reify__21892.getConnection(connection_pool.clj:29)
at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:161)
at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:147)
at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager.acquireResource(C3P0PooledConnectionPool.java:202)
at com.mchange.v2.resourcepool.BasicResourcePool.doAcquire(BasicResourcePool.java:1176)
at com.mchange.v2.resourcepool.BasicResourcePool.doAcquireAndDecrementPendingAcquiresWithinLockOnSuccess(BasicResourcePool.java:1163)
at com.mchange.v2.resourcepool.BasicResourcePool.access$700(BasicResourcePool.java:44)
at com.mchange.v2.resourcepool.BasicResourcePool$ScatteredAcquireTask.run(BasicResourcePool.java:1908)
at com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:696)
Caused by: java.net.SocketTimeoutException: Connect timed out
at java.base/sun.nio.ch.NioSocketImpl.timedFinishConnect(NioSocketImpl.java:546)
at java.base/sun.nio.ch.NioSocketImpl.connect(NioSocketImpl.java:597)
at java.base/java.net.SocksSocketImpl.connect(SocksSocketImpl.java:327)
at java.base/java.net.Socket.connect(Socket.java:633)
at org.postgresql.core.PGStream.createSocket(PGStream.java:243)
at org.postgresql.core.PGStream.(PGStream.java:98)
at org.postgresql.core.v3.ConnectionFactoryImpl.tryConnect(ConnectionFactoryImpl.java:132)
at org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl(ConnectionFactoryImpl.java:258)
... 15 more
[1ec5bbef-0057-4636-8854-568276bc71bb] 2023-11-14T21:38:01+01:00 ERROR metabase.models.field-values Error fetching field values
java.sql.SQLException: Connections could not be acquired from the underlying database!
at com.mchange.v2.sql.SqlUtils.toSQLException(SqlUtils.java:118)
at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:692)
at com.mchange.v2.c3p0.impl.AbstractPoolBackedDataSource.getConnection(AbstractPoolBackedDataSource.java:140)
at metabase.driver.sql_jdbc.execute$fn__80829$do_with_resolved_connection__80830.invoke(execute.clj:332)
at clojure.lang.AFn.applyToHelper(AFn.java:165)
at clojure.lang.AFn.applyTo(AFn.java:144)
at clojure.core$apply.invokeStatic(core.clj:667)
at clojure.core$apply.invoke(core.clj:662)
at malli.core$_instrument$fn__13254.doInvoke(core.cljc:2526)
at clojure.lang.RestFn.invoke(RestFn.java:457)
at metabase.driver.sql_jdbc.execute$fn__80861.invokeStatic(execute.clj:380)
at metabase.driver.sql_jdbc.execute$fn__80861.invoke(execute.clj:378)
at clojure.lang.MultiFn.invoke(MultiFn.java:244)
at metabase.driver.sql_jdbc.execute$execute_reducible_query.invokeStatic(execute.clj:674)
at metabase.driver.sql_jdbc.execute$execute_reducible_query.invoke(execute.clj:663)
at metabase.driver.sql_jdbc.execute$execute_reducible_query.invokeStatic(execute.clj:671)
at metabase.driver.sql_jdbc.execute$execute_reducible_query.invoke(execute.clj:663)
at metabase.driver.sql_jdbc$fn__115960.invokeStatic(sql_jdbc.clj:72)
at metabase.driver.sql_jdbc$fn__115960.invoke(sql_jdbc.clj:70)
at clojure.lang.MultiFn.invoke(MultiFn.java:244)
at metabase.query_processor.context$executef.invokeStatic(context.clj:60)
at metabase.query_processor.context$executef.invoke(context.clj:49)
at metabase.query_processor.context.default$default_runf.invokeStatic(default.clj:68)
at metabase.query_processor.context.default$default_runf.invoke(default.clj:66)
at metabase.query_processor.context$runf.invokeStatic(context.clj:46)
at metabase.query_processor.context$runf.invoke(context.clj:40)
at metabase.query_processor.reducible$identity_qp.invokeStatic(reducible.clj:15)
at metabase.query_processor.reducible$identity_qp.invoke(reducible.clj:12)
at metabase.query_processor.middleware.cache$maybe_return_cached_results$maybe_return_cached_results_STAR___77088.invoke(cache.clj:224)
at metabase.query_processor.middleware.permissions$check_query_permissions$fn__72671.invoke(permissions.clj:126)
at metabase.query_processor.middleware.mbql_to_native$mbql__GT_native$fn__76213.invoke(mbql_to_native.clj:24)
at metabase.query_processor$fn__78321$combined_post_process__78326$combined_post_process_STAR___78327.invoke(query_processor.clj:260)
at metabase.query_processor$fn__78321$combined_pre_process__78322$combined_pre_process_STAR___78323.invoke(query_processor.clj:257)
at metabase.query_processor.middleware.resolve_database_and_driver$resolve_database_and_driver$fn__76999$fn__77004.invoke(resolve_database_and_driver.clj:36)
at metabase.driver$do_with_driver.invokeStatic(driver.clj:93)
at metabase.driver$do_with_driver.invoke(driver.clj:88)
at metabase.query_processor.middleware.resolve_database_and_driver$resolve_database_and_driver$fn__76999.invoke(resolve_database_and_driver.clj:35)
at metabase.query_processor.middleware.fetch_source_query$resolve_card_id_source_tables$fn__72981.invoke(fetch_source_query.clj:316)
at metabase.query_processor.middleware.store$initialize_store$fn__73162$fn__73163.invoke(store.clj:12)
at metabase.query_processor.store$do_with_store.invokeStatic(store.clj:56)
at metabase.query_processor.store$do_with_store.invoke(store.clj:50)
at metabase.query_processor.middleware.store$initialize_store$fn__73162.invoke(store.clj:11)
at metabase.query_processor.middleware.normalize_query$normalize$fn__77295.invoke(normalize_query.clj:36)
at metabase.query_processor.reducible$async_qp$qp_STAR___66815$thunk__66817.invoke(reducible.clj:103)
at metabase.query_processor.reducible$async_qp$qp_STAR___66815.invoke(reducible.clj:109)
at metabase.query_processor.reducible$async_qp$qp_STAR___66815.invoke(reducible.clj:94)
at metabase.query_processor.reducible$async_qp$qp_STAR___66815.invoke(reducible.clj:91)
at clojure.lang.AFn.applyToHelper(AFn.java:154)
at clojure.lang.AFn.applyTo(AFn.java:144)
at clojure.core$apply.invokeStatic(core.clj:667)
at clojure.core$apply.invoke(core.clj:662)
at metabase.query_processor.reducible$sync_qp$qp_STAR___66827.doInvoke(reducible.clj:129)
at clojure.lang.RestFn.applyTo(RestFn.java:137)
at clojure.core$apply.invokeStatic(core.clj:669)
at clojure.core$apply.invoke(core.clj:662)
at metabase.query_processor$process_query.invokeStatic(query_processor.clj:295)
at metabase.query_processor$process_query.doInvoke(query_processor.clj:289)
at clojure.lang.RestFn.invoke(RestFn.java:410)
at metabase.db.metadata_queries$qp_query$fn__88312.invoke(metadata_queries.clj:23)
at metabase.db.metadata_queries$qp_query.invokeStatic(metadata_queries.clj:22)
at metabase.db.metadata_queries$qp_query.invoke(metadata_queries.clj:20)
at metabase.db.metadata_queries$field_query.invokeStatic(metadata_queries.clj:33)
at metabase.db.metadata_queries$field_query.invoke(metadata_queries.clj:31)
Trigger :
