File Download isn't working

Xabier · March 26, 2025, 11:32am

We are running the latest stable version and downloads are not working, even if the query takes 200ms the file download times out.

Reviewing the logs we can see the following:

[bd5a0eb2-e38e-4914-baf8-fd320c39535e] 2025-03-26T10:53:28+01:00 ERROR metabase.query-processor.middleware.catch-exceptions Error processing query: ERROR: canceling statement due to user request
{:database_id 3,
:parameterized false,
:started_at #t "2025-03-26T09:51:48.525699816Z[GMT]",
:via
[{:status :failed,
:class clojure.lang.ExceptionInfo,
:error "Error executing query: ERROR: canceling statement due to user request",
:stacktrace
["--> driver.sql_jdbc.execute$execute_reducible_query$fn__77628$fn__77629.invoke(execute.clj:723)"
"driver.sql_jdbc.execute$execute_reducible_query$fn__77628.invoke(execute.clj:720)"
"driver.sql_jdbc.execute$fn__77429$fn__77430.invoke(execute.clj:398)"
"driver.sql_jdbc.execute$do_with_resolved_connection77399__77400.invokeStatic(execute.clj:338)"
"driver.sql_jdbc.execute$do_with_resolved_connection77399__77400.invoke(execute.clj:321)"
"driver.sql_jdbc.execute$fn__77429.invokeStatic(execute.clj:392)"
"driver.sql_jdbc.execute$fn__77429.invoke(execute.clj:390)"
"driver.sql_jdbc.execute$execute_reducible_query.invokeStatic(execute.clj:714)"
"driver.sql_jdbc.execute$execute_reducible_query.invoke(execute.clj:700)"
"driver.sql_jdbc.execute$execute_reducible_query.invokeStatic(execute.clj:711)"
"driver.sql_jdbc.execute$execute_reducible_query.invoke(execute.clj:700)"
"driver.sql_jdbc$fn__128602.invokeStatic(sql_jdbc.clj:79)"
"driver.sql_jdbc$fn__128602.invoke(sql_jdbc.clj:77)"
"query_processor.pipeline$STAR_execute_STAR.invokeStatic(pipeline.clj:52)"
"query_processor.pipeline$STAR_execute_STAR.invoke(pipeline.clj:39)"
"query_processor.pipeline$STAR_run_STAR.invokeStatic(pipeline.clj:102)"
"query_processor.pipeline$STAR_run_STAR.invoke(pipeline.clj:95)"
"query_processor.execute$run.invokeStatic(execute.clj:62)"
"query_processor.execute$run.invoke(execute.clj:56)"
"query_processor.middleware.update_used_cards$update_used_cards_BANG_72817__72818$fn__72819.invoke(update_used_cards.clj:60)"
"query_processor.execute$add_native_form_to_result_metadata$fn__72922.invoke(execute.clj:25)"
"query_processor.execute$add_preprocessed_query_to_result_metadata_for_userland_query$fn__72928.invoke(execute.clj:36)"
"query_processor.middleware.cache$maybe_return_cached_results$maybe_return_cached_results_STAR___72790.invoke(cache.clj:241)"
"query_processor.middleware.permissions$check_query_permissions$fn__72556.invoke(permissions.clj:148)"
"query_processor.middleware.enterprise$check_download_permissions_middleware$fn__72657.invoke(enterprise.clj:57)"
"query_processor.middleware.enterprise$maybe_apply_column_level_perms_check_middleware$fn__72667.invoke(enterprise.clj:70)"
"query_processor.execute$execute72955__72956$fn__72957.invoke(execute.clj:94)"
"query_processor.setup$do_with_qp_setup72908__72909.invokeStatic(setup.clj:225)"
"query_processor.setup$do_with_qp_setup72908__72909.invoke(setup.clj:216)"
"query_processor.execute$execute72955__72956.invokeStatic(execute.clj:93)"
"query_processor.execute$execute72955__72956.invoke(execute.clj:89)"
"query_processor$process_query_STAR__STAR_.invokeStatic(query_processor.clj:49)"
"query_processor$process_query_STAR__STAR_.invoke(query_processor.clj:44)"
"query_processor.middleware.enterprise$fn__72684$handle_audit_app_internal_queries__72685$fn__72687.invoke(enterprise.clj:102)"
"query_processor.middleware.enterprise$handle_audit_app_internal_queries_middleware$fn__72695.invoke(enterprise.clj:109)"
"query_processor.middleware.process_userland_query$process_userland_query_middleware80398__80399$fn__80400.invoke(process_userland_query.clj:221)"
"query_processor.middleware.catch_exceptions$catch_exceptions80463__80464$fn__80465.invoke(catch_exceptions.clj:132)"
"query_processor$process_query80626__80627$fn__80628.invoke(query_processor.clj:80)"
"query_processor.setup$do_with_canceled_chan72903__72904$fn__72905.invoke(setup.clj:187)"
"query_processor.setup$do_with_database_local_settings72896__72897$fn__72898.invoke(setup.cl...

It seems it only happens with big files but we do no get a clear error as to why this happening, prior to upgrading it seemed tobe working.

Any ideas how to fix this ?

TonyC · March 28, 2025, 9:45am

What do you mean by the takes 200ms? The Query to return data in the UI or the actual query takes 200ms?

Keep in mind that metabase limit UI queries by 2000 for raw values and 10000 for aggregated results. When you download a file you ge the whole dataset.

How much data do you have and if you run the same query directly against your DB how long does that take to run?

Xabier · March 28, 2025, 9:56am

200 ms is the reponse time to delive the table view to Metabase, its around 250k rows. Yet when we try to download the file it times out.

The query is taking around 1 or 2 secs on our read database.

We have tried to decrease the size of the query to 50k rows and the file will still not export and timeout.

Thanks for answering.

TonyC · March 28, 2025, 9:59am

What if you limit by 1k for example? What is the size of those 50k rows? Like how many columns and wht column types do you have?

A timeout usually happens after 10 min so I would expect there to either be a timeout because of time taken to download the file or a memory error because the file is too large