Questions or Dashboards throws error first time

Get Help
1

Hi , I am using Metabase 0.32.8 version with PostgresSql as application database.

I have few set of questions created against SQL Server database and they work very well.
There are another set of questions created against MySQL database and most of the time they do not work first time and keep getting following message

" There was a problem with your question

Most of the time this is caused by an invalid selection or bad input value. Double check your inputs and retry your query."

However when i refresh those questions they start working. Could anyone help why they are behaving in this manner.
Note - MySQL database is with very small set of data

2

Hi @NaveenHadagali
Can you have a look at the log to get the full error message?

3

Here is the error from metabase log. However when i refresh it starts working
May 24 09:00:00 WARN metabase.query-processor.middleware.process-userland-query :: Query failure {:status :failed,
:class java.sql.SQLNonTransientConnectionException,
:error “(conn=1315) Read timed out”,
:stacktrace
(“org.mariadb.jdbc.internal.util.exceptions.ExceptionMapper.get(ExceptionMapper.java:234)”
“org.mariadb.jdbc.internal.util.exceptions.ExceptionMapper.getException(ExceptionMapper.java:165)”
“org.mariadb.jdbc.MariaDbStatement.executeExceptionEpilogue(MariaDbStatement.java:238)”
“org.mariadb.jdbc.MariaDbStatement.executeInternal(MariaDbStatement.java:356)”
“org.mariadb.jdbc.MariaDbStatement.executeQuery(MariaDbStatement.java:530)”
“org.mariadb.jdbc.MariaDbConnection.getTransactionIsolation(MariaDbConnection.java:951)”
“com.mchange.v2.c3p0.impl.NewProxyConnection.getTransactionIsolation(NewProxyConnection.java:277)”
“clojure.java.jdbc$db_transaction_STAR_.invokeStatic(jdbc.clj:790)”
“clojure.java.jdbc$db_transaction_STAR_.invoke(jdbc.clj:769)”
“clojure.java.jdbc$db_transaction_STAR_.invokeStatic(jdbc.clj:834)”
“clojure.java.jdbc$db_transaction_STAR_.invoke(jdbc.clj:769)”
“clojure.java.jdbc$db_transaction_STAR_.invokeStatic(jdbc.clj:782)”
“clojure.java.jdbc$db_transaction_STAR_.invoke(jdbc.clj:769)”
“–> driver.sql_jdbc.execute$do_in_transaction.invokeStatic(execute.clj:240)”
“driver.sql_jdbc.execute$do_in_transaction.invoke(execute.clj:239)”
“driver.sql_jdbc.execute$run_query_without_timezone.invokeStatic(execute.clj:258)”
“driver.sql_jdbc.execute$run_query_without_timezone.invoke(execute.clj:257)”
“driver.sql_jdbc.execute$execute_query$fn__62602.invoke(execute.clj:287)”
“driver.sql_jdbc.execute$do_with_try_catch.invokeStatic(execute.clj:216)”
“driver.sql_jdbc.execute$do_with_try_catch.invoke(execute.clj:210)”
“driver.sql_jdbc.execute$execute_query.invokeStatic(execute.clj:284)”
“driver.sql_jdbc.execute$execute_query.invoke(execute.clj:278)”
“driver.sql_jdbc$fn__62845.invokeStatic(sql_jdbc.clj:48)”
“driver.sql_jdbc$fn__62845.invoke(sql_jdbc.clj:47)”
“query_processor$fn__40384$execute_query__40389$fn__40390.invoke(query_processor.clj:61)”
“query_processor$fn__40384$execute_query__40389.invoke(query_processor.clj:55)”
“query_processor.middleware.mbql_to_native$mbql__GT_native$fn__34664.invoke(mbql_to_native.clj:36)”
“query_processor.middleware.annotate$result_rows_maps__GT_vectors$fn__32695.invoke(annotate.clj:368)”
“query_processor.middleware.annotate$add_column_info$fn__32600.invoke(annotate.clj:303)”
“query_processor.middleware.cumulative_aggregations$handle_cumulative_aggregations$fn__34144.invoke(cumulative_aggregations.clj:57)”
“query_processor.middleware.limit$limit$fn__34637.invoke(limit.clj:19)”
“query_processor.middleware.results_metadata$record_and_return_metadata_BANG_$fn__40249.invoke(results_metadata.clj:86)”
“query_processor.middleware.format_rows$format_rows$fn__34625.invoke(format_rows.clj:26)”
“query_processor.middleware.add_dimension_projections$add_remapping$fn__31752.invoke(add_dimension_projections.clj:234)”
“query_processor.middleware.add_row_count_and_status$add_row_count_and_status$fn__32146.invoke(add_row_count_and_status.clj:16)”
“query_processor.middleware.driver_specific$process_query_in_context$fn__34386.invoke(driver_specific.clj:12)”
“query_processor.middleware.resolve_driver$resolve_driver$fn__37215.invoke(resolve_driver.clj:15)”
“query_processor.middleware.bind_effective_timezone$bind_effective_timezone$fn__33550$fn__33551.invoke(bind_effective_timezone.clj:9)”
“util.date$call_with_effective_timezone.invokeStatic(date.clj:88)”
“util.date$call_with_effective_timezone.invoke(date.clj:77)”
“query_processor.middleware.bind_effective_timezone$bind_effective_timezone$fn__33550.invoke(bind_effective_timezone.clj:8)”
“query_processor.middleware.store$initialize_store$fn__40274$fn__40275.invoke(store.clj:11)”
“query_processor.store$do_with_new_store.invokeStatic(store.clj:35)”
“query_processor.store$do_with_new_store.invoke(store.clj:31)”
“query_processor.middleware.store$initialize_store$fn__40274.invoke(store.clj:10)”
“query_processor.middleware.async$async__GT_sync$fn__33146.invoke(async.clj:19)”
“query_processor.middleware.async_wait$runnable$fn__33277.invoke(async_wait.clj:71)”),
:query
{:constraints {:max-results 10000, :max-results-bare-rows 2000},
:type :native,
:middleware {:userland-query? true},
:native
{:query
“select employee,\nsum(case when hour(timediff(punch_out_user_time,punch_in_user_time))<6 then 0.5 else 1 end) as attendance,\ndate_format(punch_in_user_time,’%d-%M-%Y’) as PunchinDate\n\n from employeeview\njoin ohrm_attendance_record\non employeeview.emp_number=ohrm_attendance_record.employee_id\nwhere cast(punch_in_user_time as date)>‘2019-04-01’ \ngroup by employee, cast(punch_in_user_time as date)\n”},
:info
{:executed-by 1,
:context :question,
:card-id 162,
:dashboard-id nil,
:query-hash [40, 78, -73, 29, -38, -42, 66, -72, -111, 55, 2, 16, -10, 91, 0, -128, 37, -34, 26, 113, -38, -48, -55, -6, -9, -52, 85, 113, 112, -13, -84, 7]},
:parameters [],
:async? true,
:cache-ttl 53}}

May 24 09:00:00 DEBUG metabase.middleware.log :: POST /api/card/162/query 200 [ASYNC: completed] 19 s (11 DB calls) Jetty threads: 2/50 (4 idle, 0 queued) (57 total active threads) Queries in flight: 0
May 24 09:00:18 ERROR metabase.driver.sql-jdbc.execute :: nil
May 24 09:00:18 WARN metabase.sync.sync-metadata.sync-timezone :: Error syncing database timezone
java.lang.Exception: Error querying database ‘HR’ for current time
at metabase.driver.common$current_db_time$fn__32229.invoke(common.clj:187)
at metabase.driver.common$current_db_time.invokeStatic(common.clj:174)
at metabase.driver.common$current_db_time.invoke(common.clj:163)
at clojure.lang.AFn.applyToHelper(AFn.java:156)
at clojure.lang.AFn.applyTo(AFn.java:144)
at clojure.core$apply.invokeStatic(core.clj:665)
at clojure.core$apply.invoke(core.clj:660)
at metabase.driver.mysql$fn__62622.invokeStatic(mysql.clj:91)
at metabase.driver.mysql$fn__62622.doInvoke(mysql.clj:90)
at clojure.lang.RestFn.invoke(RestFn.java:421)
at clojure.lang.MultiFn.invoke(MultiFn.java:234)
at metabase.sync.sync_metadata.sync_timezone$fn__47137$sync_timezone_BANG___47142$fn__47143.invoke(sync_timezone.clj:24)
at metabase.sync.sync_metadata.sync_timezone$fn__47137$sync_timezone_BANG___47142.invoke(sync_timezone.clj:14)
at metabase.sync.util$fn__37761$run_step_with_metadata__37766$fn__37770$fn__37772.invoke(util.clj:356)
at metabase.sync.util$with_start_and_finish_logging_SINGLEQUOTE_.invokeStatic(util.clj:104)
at metabase.sync.util$with_start_and_finish_logging_SINGLEQUOTE_.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__37761$run_step_with_metadata__37766$fn__37770.invoke(util.clj:353)
at metabase.sync.util$fn__37761$run_step_with_metadata__37766.invoke(util.clj:348)
at metabase.sync.util$fn__37953$run_sync_operation__37958$fn__37959$fn__37960.invoke(util.clj:441)
at clojure.core$mapv$fn__8430.invoke(core.clj:6912)
at clojure.lang.PersistentVector.reduce(PersistentVector.java:343)
at clojure.core$reduce.invokeStatic(core.clj:6827)
at clojure.core$mapv.invokeStatic(core.clj:6903)
at clojure.core$mapv.invoke(core.clj:6903)
at metabase.sync.util$fn__37953$run_sync_operation__37958$fn__37959.invoke(util.clj:441)
at metabase.sync.util$fn__37953$run_sync_operation__37958.invoke(util.clj:435)
at metabase.sync.sync_metadata$fn__47495$sync_db_metadata_BANG___47500$fn__47501$fn__47502.invoke(sync_metadata.clj:51)
at metabase.sync.util$do_with_error_handling.invokeStatic(util.clj:149)
at metabase.sync.util$do_with_error_handling.invoke(util.clj:144)
at metabase.sync.util$do_with_error_handling.invokeStatic(util.clj:147)
at metabase.sync.util$do_with_error_handling.invoke(util.clj:144)
at clojure.core$partial$fn__5824.invoke(core.clj:2623)
at metabase.driver$fn__18010.invokeStatic(driver.clj:646)
at metabase.driver$fn__18010.invoke(driver.clj:646)
at clojure.lang.MultiFn.invoke(MultiFn.java:239)
at metabase.sync.util$sync_in_context$fn__37667.invoke(util.clj:140)
at metabase.sync.util$with_db_logging_disabled$fn__37664.invoke(util.clj:131)
at metabase.sync.util$with_start_and_finish_logging_SINGLEQUOTE_.invokeStatic(util.clj:104)
at metabase.sync.util$with_start_and_finish_logging_SINGLEQUOTE_.invoke(util.clj:98)
at metabase.sync.util$with_start_and_finish_logging$fn__37653.invoke(util.clj:116)
at metabase.sync.util$with_sync_events$fn__37648.invoke(util.clj:90)
at metabase.sync.util$with_duplicate_ops_prevented$fn__37639.invoke(util.clj:69)
at metabase.sync.util$do_sync_operation.invokeStatic(util.clj:168)
at metabase.sync.util$do_sync_operation.invoke(util.clj:165)
at metabase.sync.sync_metadata$fn__47495$sync_db_metadata_BANG___47500$fn__47501.invoke(sync_metadata.clj:50)
at metabase.sync.sync_metadata$fn__47495$sync_db_metadata_BANG___47500.invoke(sync_metadata.clj:47)
at metabase.task.sync_databases.SyncAndAnalyzeDatabase.execute(sync_databases.clj:39)
at org.quartz.core.JobRunShell.run(JobRunShell.java:213)
at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:557)
Caused by: java.sql.SQLNonTransientConnectionException: (conn=1308) Read timed out
at org.mariadb.jdbc.internal.util.exceptions.ExceptionMapper.get(ExceptionMapper.java:234)
at org.mariadb.jdbc.internal.util.exceptions.ExceptionMapper.getException(ExceptionMapper.java:165)
at org.mariadb.jdbc.MariaDbStatement.executeExceptionEpilogue(MariaDbStatement.java:238)
at org.mariadb.jdbc.MariaDbStatement.executeInternal(MariaDbStatement.java:356)
at org.mariadb.jdbc.MariaDbStatement.executeQuery(MariaDbStatement.java:530)
at org.mariadb.jdbc.MariaDbConnection.getTransactionIsolation(MariaDbConnection.java:951)
at com.mchange.v2.c3p0.impl.NewProxyConnection.getTransactionIsolation(NewProxyConnection.java:277)
at clojure.java.jdbc$db_transaction_STAR_.invokeStatic(jdbc.clj:790)
at clojure.java.jdbc$db_transaction_STAR_.invoke(jdbc.clj:769)
at clojure.java.jdbc$db_transaction_STAR_.invokeStatic(jdbc.clj:834)
at clojure.java.jdbc$db_transaction_STAR_.invoke(jdbc.clj:769)
at clojure.java.jdbc$db_transaction_STAR_.invokeStatic(jdbc.clj:782)
at clojure.java.jdbc$db_transaction_STAR_.invoke(jdbc.clj:769)
at metabase.driver.sql_jdbc.execute$do_in_transaction.invokeStatic(execute.clj:240)
at metabase.driver.sql_jdbc.execute$do_in_transaction.invoke(execute.clj:239)
at metabase.driver.sql_jdbc.execute$run_query_without_timezone.invokeStatic(execute.clj:258)
at metabase.driver.sql_jdbc.execute$run_query_without_timezone.invoke(execute.clj:257)
at metabase.driver.sql_jdbc.execute$execute_query$fn__62602.invoke(execute.clj:287)
at metabase.driver.sql_jdbc.execute$do_with_try_catch.invokeStatic(execute.clj:216)
at metabase.driver.sql_jdbc.execute$do_with_try_catch.invoke(execute.clj:210)
at metabase.driver.sql_jdbc.execute$execute_query.invokeStatic(execute.clj:284)
at metabase.driver.sql_jdbc.execute$execute_query.invoke(execute.clj:278)
at metabase.driver.sql_jdbc$fn__62845.invokeStatic(sql_jdbc.clj:48)
at metabase.driver.sql_jdbc$fn__62845.invoke(sql_jdbc.clj:47)
at clojure.lang.MultiFn.invoke(MultiFn.java:234)
at metabase.driver.common$current_db_time$fn__32229$fn__32230.invoke(common.clj:180)
at metabase.query_processor.store$do_with_new_store.invokeStatic(store.clj:35)
at metabase.query_processor.store$do_with_new_store.invoke(store.clj:31)
at metabase.driver.common$current_db_time$fn__32229.invoke(common.clj:177)
… 50 more
Caused by: java.sql.SQLException: Read timed out
Query is: SELECT @@tx_isolation
java thread: MetabaseScheduler_Worker-8
at org.mariadb.jdbc.internal.util.LogQueryTool.exceptionWithQuery(LogQueryTool.java:126)
at org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol.executeQuery(AbstractQueryProtocol.java:222)
at org.mariadb.jdbc.MariaDbStatement.executeInternal(MariaDbStatement.java:350)
… 75 more
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(Unknown Source)
at java.net.SocketInputStream.read(Unknown Source)
at java.net.SocketInputStream.read(Unknown Source)
at java.io.BufferedInputStream.fill(Unknown Source)
at java.io.BufferedInputStream.read1(Unknown Source)
at java.io.BufferedInputStream.read(Unknown Source)
at org.mariadb.jdbc.internal.io.input.DecompressPacketInputStream.readBlocking(DecompressPacketInputStream.java:193)
at org.mariadb.jdbc.internal.io.input.DecompressPacketInputStream.getPacketArray(DecompressPacketInputStream.java:117)
at org.mariadb.jdbc.internal.io.input.DecompressPacketInputStream.getPacket(DecompressPacketInputStream.java:97)
at org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol.readPacket(AbstractQueryProtocol.java:1427)
at org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol.getResult(AbstractQueryProtocol.java:1407)
at org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol.executeQuery(AbstractQueryProtocol.java:219)
… 76 more
May 24 09:00:18 DEBUG metabase.sync.util :: FINISHED: step ‘sync-timezone’ for mysql Database 35 ‘HR’ (19 s)
May 24 09:00:18 DEBUG metabase.sync.util :: STARTING: step ‘sync-tables’ for mysql Database 35 ‘HR’
May 24 09:00:18 DEBUG metabase.sync.util :: FINISHED: step ‘sync-tables’ for mysql Database 35 ‘HR’ (29 ms)
May 24 09:00:18 DEBUG metabase.sync.util :: STARTING: step ‘sync-fields’ for mysql Database 35 ‘HR’
May 24 09:00:19 DEBUG metabase.sync.sync-metadata.fields :: Hash of Table 287 ‘employeeleaves’ matches stored hash, skipping Fields sync
May 24 09:00:19 DEBUG metabase.sync.sync-metadata.fields :: Hash of Table 341 ‘projectattendance’ matches stored hash, skipping Fields sync
May 24 09:00:19 DEBUG metabase.sync.sync-metadata.fields :: Hash of Table 389 ‘employee details’ matches stored hash, skipping Fields sync
May 24 09:00:19 DEBUG metabase.sync.sync-metadata.fields :: Hash of Table 421 ‘employeeview’ matches stored hash, skipping Fields sync
May 24 09:00:19 DEBUG metabase.sync.sync-metadata.fields :: Hash of Table 422 ‘projectwiseattendance’ matches stored hash, skipping Fields sync
May 24 09:00:19 DEBUG metabase.sync.sync-metadata.fields :: Hash of Table 423 ‘employeeattendance’ matches stored hash, skipping Fields sync
May 24 09:00:19 DEBUG metabase.sync.util :: FINISHED: step ‘sync-fields’ for mysql Database 35 ‘HR’ (452 ms)
May 24 09:00:19 DEBUG metabase.sync.util :: STARTING: step ‘sync-fks’ for mysql Database 35 ‘HR’
May 24 09:00:19 DEBUG metabase.sync.util :: FINISHED: step ‘sync-fks’ for mysql Database 35 ‘HR’ (331 ms)
May 24 09:00:19 DEBUG metabase.sync.util :: STARTING: step ‘sync-metabase-metadata’ for mysql Database 35 ‘HR’
May 24 09:00:19 DEBUG metabase.sync.util :: FINISHED: step ‘sync-metabase-metadata’ for mysql Database 35 ‘HR’ (14 ms)
May 24 09:00:19 DEBUG metabase.sync.util ::
#################################################################

4

I think i found solution, TimeZone was not set in Metabase and looking at error above , i set it to database default. After doing that when i try to access the dashboard it comes back without error first time aswell.

Will keep checking for rest of the day and let you know how it goes

5

Okay, great. With what you describe and what the log says, it sounds like you’re experiencing issue #9989 and/or #9885. Go and click the Subscribe-button on the issue to get notification when anything happens.