Embedded Dashboard Log Issues Since upgrade from 43.3 - Slow performance

Bug reports
1

We are currently hosting Metabase v44.0 on Beanstalk with Postgres config DB and SQL Server 2019 data. It is embedded in our application and we are passing URL parameters to filter down results to 2 locked filters. When we upgraded from 43.3 - 43.4(I believe that was the version) all of our dashboards slowed down by a factor of 2-3x. I finally got around to reviewing the logs to try to determine what might be causing the slow down. The interesting thing is that if I run the dashboard natively within Metabase I do not get any errors in the log. When I run embedded I get the following example error. Did something change on how Metabase received querysting parameters or any ideas on where to start troubleshooting?

Thanks!

[a54bf8c1-8b35-444b-8283-9b48260aebb0] 2022-08-24T18:24:29-05:00 ERROR metabase.query-processor.middleware.catch-exceptions Error processing query: Closed
{:database_id 1,
:started_at #t "2022-08-24T23:24:13.518058Z[GMT]",
:json_query
{:constraints {:max-results 10000, :max-results-bare-rows 2000},
:type :query,
:middleware {:js-int-to-string? true, :ignore-cached-results? false},
:database 14,
:query
{:source-table 731,
:filter [:= [:field 1909 nil] "SuperSport"],
:aggregation
[[:aggregation-options
[:-
[:* [:confused: [:count-where [:>= [:field 1888 nil] 90]] [:count-where [:>= [:field 1888 nil] 0]]] 100]
[:* [:confused: [:count-where [:<= [:field 1888 nil] 60]] [:count-where [:>= [:field 1888 nil] 0]]] 100]]
{:display-name "Score"}]
[:count]],
:breakout [[:field 1876 {:temporal-unit :month}] [:field 1920 nil]]},
:parameters
[{:type :date/all-options,
:slug "date_filter",
:id "6cc0fea",
:value "past12months~",
:target [:dimension [:field 1876 nil]]}
{:type :category,
:slug "UniqueId",
:id "844bb381",
:value
["518802"
"520316"
"522055"
"522497"
"522822"
"523387"
"524066"
"525208"
"525365"
"536659"
"536660"
"536661"
"536662"
"536663"
"536664"
"543101"
"543900"],
:target [:dimension [:field 1906 nil]]}
{:type :category, :slug "CustomerId", :id "6d7cc0f6", :value 22, :target [:dimension [:field 2158 nil]]}],
:async? true,
:cache-ttl nil},
:native
{:query
"SELECT DateFromParts(year("dbo"."SourceData"."Date"), month("dbo"."SourceData"."Date"), 1) AS "Date", "dbo"."SourceData"."SurveyType" AS "SurveyType", (((CAST(sum(CASE WHEN "dbo"."SourceData"."Response" >= 90 THEN 1 ELSE 0.0 END) AS float) / CASE WHEN sum(CASE WHEN "dbo"."SourceData"."Response" >= 0 THEN 1 ELSE 0.0 END) = 0 THEN NULL ELSE sum(CASE WHEN "dbo"."SourceData"."Response" >= 0 THEN 1 ELSE 0.0 END) END) * 100) - ((CAST(sum(CASE WHEN "dbo"."SourceData"."Response" <= 60 THEN 1 ELSE 0.0 END) AS float) / CASE WHEN sum(CASE WHEN "dbo"."SourceData"."Response" >= 0 THEN 1 ELSE 0.0 END) = 0 THEN NULL ELSE sum(CASE WHEN "dbo"."SourceData"."Response" >= 0 THEN 1 ELSE 0.0 END) END) * 100)) AS "expression", count() AS "count" FROM "dbo"."SourceData" WHERE ("dbo"."SourceData"."TextValue" = ? AND "dbo"."SourceData"."Date" >= DateFromParts(year(dateadd(month, -12, getdate())), month(dateadd(month, -12, getdate())), 1) AND "dbo"."SourceData"."Date" < DateFromParts(year(dateadd(month, 1, getdate())), month(dateadd(month, 1, getdate())), 1) AND ("dbo"."SourceData"."UniqueId" = 518802 OR "dbo"."SourceData"."UniqueId" = 520316 OR "dbo"."SourceData"."UniqueId" = 522055 OR "dbo"."SourceData"."UniqueId" = 522497 OR "dbo"."SourceData"."UniqueId" = 522822 OR "dbo"."SourceData"."UniqueId" = 523387 OR "dbo"."SourceData"."UniqueId" = 524066 OR "dbo"."SourceData"."UniqueId" = 525208 OR "dbo"."SourceData"."UniqueId" = 525365 OR "dbo"."SourceData"."UniqueId" = 536659 OR "dbo"."SourceData"."UniqueId" = 536660 OR "dbo"."SourceData"."UniqueId" = 536661 OR "dbo"."SourceData"."UniqueId" = 536662 OR "dbo"."SourceData"."UniqueId" = 536663 OR "dbo"."SourceData"."UniqueId" = 536664 OR "dbo"."SourceData"."UniqueId" = 543101 OR "dbo"."SourceData"."UniqueId" = 543900) AND "dbo"."SourceData"."CustomerId" = 22) GROUP BY year("dbo"."SourceData"."Date"), month("dbo"."SourceData"."Date"), "dbo"."SourceData"."SurveyType" ORDER BY year("dbo"."SourceData"."Date") ASC, month("dbo"."SourceData"."Date") ASC, "dbo"."SourceData"."SurveyType" ASC",
:params ("SuperSport")},
:status :failed,
:class org.eclipse.jetty.io.EofException,
:stacktrace
["org.eclipse.jetty.server.HttpOutput.checkWritable(HttpOutput.java:765)"
"org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:795)"
"java.base/java.io.OutputStream.write(Unknown Source)"
"java.base/java.util.zip.GZIPOutputStream.writeHeader(Unknown Source)"
"java.base/java.util.zip.GZIPOutputStream.(Unknown Source)"
"java.base/java.util.zip.GZIPOutputStream.(Unknown Source)"
"--> async.streaming_response$output_stream_delay$fn__19016.invoke(streaming_response.clj:107)"
"async.streaming_response$delay_output_stream$fn__19025.invoke(streaming_response.clj:124)"
"async.streaming_response.proxy$java.io.OutputStream$ff19274a.write(Unknown Source)"
"query_processor.streaming.json$fn$reify__39060.finish_BANG_(json.clj:91)"
"query_processor.streaming$streaming_reducedf$fn__39839.invoke(streaming.clj:128)"
"api.public$public_reducedf$fn__72792.invoke(public.clj:96)"
"query_processor.context$reducedf.invokeStatic(context.clj:77)"
"query_processor.context$reducedf.invoke(context.clj:72)"
"query_processor.context.default$default_reducef.invokeStatic(default.clj:63)"
"query_processor.context.default$default_reducef.invoke(default.clj:48)"
"query_processor.context$reducef.invokeStatic(context.clj:69)"
"query_processor.context$reducef.invoke(context.clj:62)"
"query_processor.context.default$default_runf$respond_STAR___38794.invoke(default.clj:68)"
"driver.sql_jdbc.execute$execute_reducible_query.invokeStatic(execute.clj:507)"
"driver.sql_jdbc.execute$execute_reducible_query.invoke(execute.clj:486)"
"driver.sql_jdbc.execute$execute_reducible_query.invokeStatic(execute.clj:494)"
"driver.sql_jdbc.execute$execute_reducible_query.invoke(execute.clj:486)"
"driver.sql_jdbc$fn__84993.invokeStatic(sql_jdbc.clj:54)"
"driver.sql_jdbc$fn__84993.invoke(sql_jdbc.clj:52)"
"query_processor.context$executef.invokeStatic(context.clj:59)"
"query_processor.context$executef.invoke(context.clj:48)"
"query_processor.context.default$default_runf.invokeStatic(default.clj:67)"
"query_processor.context.default$default_runf.invoke(default.clj:65)"
"query_processor.context$runf.invokeStatic(context.clj:45)"
"query_processor.context$runf.invoke(context.clj:39)"
"query_processor.reducible$identity_qp.invokeStatic(reducible.clj:12)"
"query_processor.reducible$identity_qp.invoke(reducible.clj:9)"
"query_processor.middleware.cache$maybe_return_cached_results$maybe_return_cached_results_STAR___52408.invoke(cache.clj:220)"
"query_processor.middleware.permissions$check_query_permissions$fn__47931.invoke(permissions.clj:109)"
"query_processor.middleware.mbql_to_native$mbql__GT_native$fn__51349.invoke(mbql_to_native.clj:23)"
"query_processor$fn__53884$combined_post_process__53889$combined_post_process_STAR___53890.invoke(query_processor.clj:211)"
"query_processor$fn__53884$combined_pre_process__53885$combined_pre_process_STAR___53886.invoke(query_processor.clj:208)"
"query_processor.middleware.resolve_database_and_driver$resolve_database_and_driver$fn__52329$fn__52334.invoke(resolve_database_and_driver.clj:35)"
"driver$do_with_driver.invokeStatic(driver.clj:75)"
"driver$do_with_driver.invoke(driver.clj:71)"
"query_processor.middleware.resolve_database_and_driver$resolve_database_and_driver$fn__52329.invoke(resolve_database_and_driver.clj:34)"
"query_processor.middleware.fetch_source_query$resolve_card_id_source_tables$fn__48363.invoke(fetch_source_query.clj:346)"
"query_processor.middleware.store$initialize_store$fn__48551$fn__48552.invoke(store.clj:11)"
"query_processor.store$do_with_store.invokeStatic(store.clj:44)"
"query_processor.store$do_with_store.invoke(store.clj:38)"
"query_processor.middleware.store$initialize_store$fn__48551.invoke(store.clj:10)"
"query_processor.middleware.normalize_query$normalize$fn__52601.invoke(normalize_query.clj:22)"
"query_processor.middleware.constraints$add_default_userland_constraints$fn__49633.invoke(constraints.clj:53)"
"query_processor.middleware.process_userland_query$process_userland_query$fn__52540.invoke(process_userland_query.clj:145)"
"query_processor.middleware.catch_exceptions$catch_exceptions$fn__52912.invoke(catch_exceptions.clj:167)"
"query_processor.reducible$async_qp$qp_STAR___44771$thunk__44773.invoke(reducible.clj:100)"
"query_processor.reducible$async_qp$qp_STAR___44771$fn__44775.invoke(reducible.clj:105)"],
:card_id 20,
:context :embedded-dashboard,
:error "Closed",
:row_count 0,
:running_time 0,
:preprocessed
{:constraints {:max-results 10000, :max-results-bare-rows 2000},
:type :query,
:middleware {:js-int-to-string? true, :ignore-cached-results? false},
:user-parameters
[{:type :date/all-options,
:slug "date_filter",
:id "6cc0fea",
:value "past12months~",
:target [:dimension [:field 1876 nil]]}
{:type :category,
:slug "UniqueId",
:id "844bb381",
:value
["518802"
"520316"
"522055"
"522497"
"522822"
"523387"
"524066"
"525208"
"525365"
"536659"
"536660"
"536661"
"536662"
"536663"
"536664"
"543101"
"543900"],
:target [:dimension [:field 1906 nil]]}
{:type :category, :slug "CustomerId", :id "6d7cc0f6", :value 22, :target [:dimension [:field 2158 nil]]}],
:info
{:executed-by 1, :context :embedded-dashboard, :card-id 20, :card-name "Score Trend", :dashboard-id 1},
:database 14,
:query
{:source-table 731,
:filter
[:and
[:=
[:field 1909 nil]
[:value
"SuperSport"
{:base_type :type/Text,
:effective_type :type/Text,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "nvarchar",
:name "TextValue"}]]
[:>= [:field 1876 {:temporal-unit :default}] [:relative-datetime -12 :month]]
[:< [:field 1876 {:temporal-unit :default}] [:relative-datetime 1 :month]]
[:or
[:=
[:field 1906 nil]
[:value
518802
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]
[:=
[:field 1906 nil]
[:value
520316
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]
[:=
[:field 1906 nil]
[:value
522055
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]
[:=
[:field 1906 nil]
[:value
522497
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]
[:=
[:field 1906 nil]
[:value
522822
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]
[:=
[:field 1906 nil]
[:value
523387
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]
[:=
[:field 1906 nil]
[:value
524066
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]
[:=
[:field 1906 nil]
[:value
525208
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]
[:=
[:field 1906 nil]
[:value
525365
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]
[:=
[:field 1906 nil]
[:value
536659
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]
[:=
[:field 1906 nil]
[:value
536660
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]
[:=
[:field 1906 nil]
[:value
536661
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]
[:=
[:field 1906 nil]
[:value
536662
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]
[:=
[:field 1906 nil]
[:value
536663
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]
[:=
[:field 1906 nil]
[:value
536664
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]
[:=
[:field 1906 nil]
[:value
543101
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]
[:=
[:field 1906 nil]
[:value
543900
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "UniqueId"}]]]
[:=
[:field 2158 nil]
[:value
22
{:base_type :type/Integer,
:effective_type :type/Integer,
:coercion_strategy nil,
:semantic_type :type/Category,
:database_type "int",
:name "CustomerId"}]]],
:aggregation
[[:aggregation-options
[:-
[:
[:confused:
[:count-where
[:>=
[:field 1888 nil]
[:value
90
{:base_type :type/Decimal,
:effective_type :type/Decimal,
:coercion_strategy nil,
:semantic_type nil,
:database_type "numeric",
:name "Response"}]]]
[:count-where
[:>=
[:field 1888 nil]
[:value
0
{:base_type :type/Decimal,
:effective_type :type/Decimal,
:coercion_strategy nil,
:semantic_type nil,
:database_type "numeric",
:name "Response"}]]]]
100]
[:*
[:confused:
[:count-where
[:<=
[:field 1888 nil]
[:value
60
{:base_type :type/Decimal,
:effective_type :type/Decimal,
:coercion_strategy nil,
:semantic_type nil,
:database_type "numeric",
:name "Response"}]]]
[:count-where
[:>=
[:field 1888 nil]
[:value
0
{:base_type :type/Decimal,
:effective_type :type/Decimal,
:coercion_strategy nil,
:semantic_type nil,
:database_type "numeric",
:name "Response"}]]]]
100]]
{:display-name "Score", :name "expression"}]
[:aggregation-options [:count] {:name "count"}]],
:breakout [[:field 1876 {:temporal-unit :month}] [:field 1920 nil]],
:order-by [[:asc [:field 1876 {:temporal-unit :month}]] [:asc [:field 1920 nil]]]},
:async? true},
:data {:rows [], :cols []}}

2

Hi @Slickrock22
You should have seen the same problem on all previous versions too:
https://github.com/metabase/metabase/issues/17061 - upvote by clicking :+1: on the first post