We upgraded some 10.1.48 MariaDB servers to 10.6.17 and noticed that some query times increased a lot by using the default
optimization.
MariaDB [marvin_production]> set optimizer_switch='rowid_filter=on';
|
MariaDB [marvin_production]> analyze SELECT `payment_sessions`.* FROM `payment_sessions` WHERE `payment_sessions`.`status` = 1 AND `payment_sessions`.`attempted` = FALSE AND `payment_sessions`.`payable_id` = 61700906 AND `payment_sessions`.`payable_type` = 'Ecommerce::Order' AND `payment_sessions`.`user_id` = 6204898 ORDER BY id desc;
|
+------+-------------+--------------------------+------------+--------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------+---------------------------------------------------------------------------------------------------------+---------+-------------+--------+-----------+----------+------------+---------------------------------+
|
| id | select_type | table | type | possible_keys | key | key_len | ref | rows | r_rows | filtered | r_filtered | Extra |
|
+------+-------------+--------------------------+------------+--------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------+---------------------------------------------------------------------------------------------------------+---------+-------------+--------+-----------+----------+------------+---------------------------------+
|
| 1 | SIMPLE | payment_sessions | ref|filter | index_payment_sessions_on_status_and_attempted,index_payment_sessions_on_payable_id_and_payable_type,index_payment_sessions_on_user_id,index_payment_sessions_on_status_attempted_and_updated_at | index_payment_sessions_on_payable_id_and_payable_type|index_payment_sessions_on_user_id | 773|5 | const,const | 1 (0%) | 1.00 (0%) | 0.00 | 100.00 | Using where; Using rowid filter |
|
+------+-------------+--------------------------+------------+--------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------+---------------------------------------------------------------------------------------------------------+---------+-------------+--------+-----------+----------+------------+---------------------------------+
|
1 row in set (6.237 sec)
|
|
|
MariaDB [marvin_production]> set optimizer_switch='rowid_filter=off';
|
Query OK, 0 rows affected (0.000 sec)
|
|
MariaDB [marvin_production]> analyze SELECT `payment_sessions`.* FROM `payment_sessions` WHERE `payment_sessions`.`status` = 1 AND `payment_sessions`.`attempted` = FALSE AND `payment_sessions`.`payable_id` = 61700906 AND `payment_sessions`.`payable_type` = 'Ecommerce::Order' AND `payment_sessions`.`user_id` = 6204898 ORDER BY id desc;
|
+------+-------------+--------------------------+------+--------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------+-------------------------------------------+---------+-------+------+--------+----------+------------+-------------+
|
| id | select_type | table | type | possible_keys | key | key_len | ref | rows | r_rows | filtered | r_filtered | Extra |
|
+------+-------------+--------------------------+------+--------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------+-------------------------------------------+---------+-------+------+--------+----------+------------+-------------+
|
| 1 | SIMPLE | payment_sessions | ref | index_payment_sessions_on_status_and_attempted,index_payment_sessions_on_payable_id_and_payable_type,index_payment_sessions_on_user_id,index_payment_sessions_on_status_attempted_and_updated_at | index_payment_sessions_on_user_id | 5 | const | 1 | 1.00 | 0.00 | 100.00 | Using where |
|
+------+-------------+--------------------------+------+--------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------+-------------------------------------------+---------+-------+------+--------+----------+------------+-------------+
|
1 row in set (0.000 sec)
|
|
ANALYZE with format=json output can be seen below:
{
|
"query_block": {
|
"select_id": 1,
|
"r_loops": 1,
|
"r_total_time_ms": 20063.527,
|
"table": {
|
"table_name": "payment_sessions",
|
"access_type": "ref",
|
"possible_keys": [
|
"index_payment_sessions_on_status_and_attempted",
|
"index_payment_sessions_on_payable_id_and_payable_type",
|
"index_payment_sessions_on_user_id",
|
"index_payment_sessions_on_status_attempted_and_updated_at"
|
],
|
"key": "index_payment_sessions_on_payable_id_and_payable_type",
|
"key_length": "773",
|
"used_key_parts": ["payable_id", "payable_type"],
|
"ref": ["const", "const"],
|
"rowid_filter": {
|
"range": {
|
"key": "index_payment_sessions_on_user_id",
|
"used_key_parts": ["user_id"]
|
},
|
"rows": 1,
|
"selectivity_pct": 3.321769e-6,
|
"r_rows": 1,
|
"r_lookups": 29100206,
|
"r_selectivity_pct": 3.436402e-6,
|
"r_buffer_size": 0,
|
"r_filling_time_ms": 0.011
|
},
|
"r_loops": 1,
|
"rows": 1,
|
"r_rows": 1,
|
"r_table_time_ms": 20063.509,
|
"r_other_time_ms": 0.012,
|
"r_engine_stats": {
|
"pages_accessed": 82910,
|
"pages_read_count": 65125,
|
"pages_read_time_ms": 9629.967
|
},
|
"filtered": 3.88882e-7,
|
"r_filtered": 100,
|
"attached_condition": "payment_sessions.payable_id <=> 61700906 and payment_sessions.payable_type <=> 'Ecommerce::Order' and payment_sessions.`status` = 1 and payment_sessions.attempted = 0 and payment_sessions.user_id = 6204898 and payment_sessions.payable_type = 'Ecommerce::Order'"
|
}
|
}
|
}
|
There is not much information in the relevant docs on the cases where it's advisable to disable this optimization, but for now we have disabled it globally and performance is equivalent to 10.1.
{"report":{"fcp":627.7999999523163,"ttfb":160.70000004768372,"pageVisibility":"visible","entityId":129727,"key":"jira.project.issue.view-issue","isInitial":true,"threshold":1000,"elementTimings":{},"userDeviceMemory":8,"userDeviceProcessors":64,"apdex":1,"journeyId":"42554732-e447-4f31-90f3-4123dd075b55","navigationType":0,"readyForUser":759.1000001430511,"redirectCount":0,"resourceLoadedEnd":803.7000000476837,"resourceLoadedStart":166.5,"resourceTiming":[{"duration":6.200000047683716,"initiatorType":"link","name":"https://jira.mariadb.org/s/2c21342762a6a02add1c328bed317ffd-CDN/lu2bu7/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/css/_super/batch.css","startTime":166.5,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":166.5,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":172.70000004768372,"responseStart":0,"secureConnectionStart":0},{"duration":9.800000190734863,"initiatorType":"link","name":"https://jira.mariadb.org/s/7ebd35e77e471bc30ff0eba799ebc151-CDN/lu2bu7/820016/12ta74/8679b4946efa1a0bb029a3a22206fb5d/_/download/contextbatch/css/jira.browse.project,project.issue.navigator,jira.view.issue,jira.general,jira.global,atl.general,-_super/batch.css?agile_global_admin_condition=true&jag=true&jira.create.linked.issue=true&slack-enabled=true","startTime":166.79999995231628,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":166.79999995231628,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":176.60000014305115,"responseStart":0,"secureConnectionStart":0},{"duration":64.40000009536743,"initiatorType":"script","name":"https://jira.mariadb.org/s/fbf975c0cce4b1abf04784eeae9ba1f4-CDN/lu2bu7/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/js/_super/batch.js?locale=en","startTime":166.79999995231628,"connectEnd":166.79999995231628,"connectStart":166.79999995231628,"domainLookupEnd":166.79999995231628,"domainLookupStart":166.79999995231628,"fetchStart":166.79999995231628,"redirectEnd":0,"redirectStart":0,"requestStart":166.79999995231628,"responseEnd":231.20000004768372,"responseStart":231.20000004768372,"secureConnectionStart":166.79999995231628},{"duration":141.60000014305115,"initiatorType":"script","name":"https://jira.mariadb.org/s/099b33461394b8015fc36c0a4b96e19f-CDN/lu2bu7/820016/12ta74/8679b4946efa1a0bb029a3a22206fb5d/_/download/contextbatch/js/jira.browse.project,project.issue.navigator,jira.view.issue,jira.general,jira.global,atl.general,-_super/batch.js?agile_global_admin_condition=true&jag=true&jira.create.linked.issue=true&locale=en&slack-enabled=true","startTime":167,"connectEnd":167,"connectStart":167,"domainLookupEnd":167,"domainLookupStart":167,"fetchStart":167,"redirectEnd":0,"redirectStart":0,"requestStart":167,"responseEnd":308.60000014305115,"responseStart":308.5,"secureConnectionStart":167},{"duration":144.89999985694885,"initiatorType":"script","name":"https://jira.mariadb.org/s/94c15bff32baef80f4096a08aceae8bc-CDN/lu2bu7/820016/12ta74/c92c0caa9a024ae85b0ebdbed7fb4bd7/_/download/contextbatch/js/atl.global,-_super/batch.js?locale=en","startTime":167.10000014305115,"connectEnd":167.10000014305115,"connectStart":167.10000014305115,"domainLookupEnd":167.10000014305115,"domainLookupStart":167.10000014305115,"fetchStart":167.10000014305115,"redirectEnd":0,"redirectStart":0,"requestStart":167.10000014305115,"responseEnd":312,"responseStart":312,"secureConnectionStart":167.10000014305115},{"duration":145.10000014305115,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bu7/820016/12ta74/1.0/_/download/batch/jira.webresources:calendar-en/jira.webresources:calendar-en.js","startTime":167.29999995231628,"connectEnd":167.29999995231628,"connectStart":167.29999995231628,"domainLookupEnd":167.29999995231628,"domainLookupStart":167.29999995231628,"fetchStart":167.29999995231628,"redirectEnd":0,"redirectStart":0,"requestStart":167.29999995231628,"responseEnd":312.40000009536743,"responseStart":312.40000009536743,"secureConnectionStart":167.29999995231628},{"duration":145.5,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bu7/820016/12ta74/1.0/_/download/batch/jira.webresources:calendar-localisation-moment/jira.webresources:calendar-localisation-moment.js","startTime":167.29999995231628,"connectEnd":167.29999995231628,"connectStart":167.29999995231628,"domainLookupEnd":167.29999995231628,"domainLookupStart":167.29999995231628,"fetchStart":167.29999995231628,"redirectEnd":0,"redirectStart":0,"requestStart":167.29999995231628,"responseEnd":312.7999999523163,"responseStart":312.7999999523163,"secureConnectionStart":167.29999995231628},{"duration":145.70000004768372,"initiatorType":"link","name":"https://jira.mariadb.org/s/b04b06a02d1959df322d9cded3aeecc1-CDN/lu2bu7/820016/12ta74/a2ff6aa845ffc9a1d22fe23d9ee791fc/_/download/contextbatch/css/jira.global.look-and-feel,-_super/batch.css","startTime":167.5,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":167.5,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":313.2000000476837,"responseStart":0,"secureConnectionStart":0},{"duration":145.70000004768372,"initiatorType":"script","name":"https://jira.mariadb.org/rest/api/1.0/shortcuts/820016/47140b6e0a9bc2e4913da06536125810/shortcuts.js?context=issuenavigation&context=issueaction","startTime":167.5,"connectEnd":167.5,"connectStart":167.5,"domainLookupEnd":167.5,"domainLookupStart":167.5,"fetchStart":167.5,"redirectEnd":0,"redirectStart":0,"requestStart":167.5,"responseEnd":313.2000000476837,"responseStart":313.2000000476837,"secureConnectionStart":167.5},{"duration":145.79999995231628,"initiatorType":"link","name":"https://jira.mariadb.org/s/3ac36323ba5e4eb0af2aa7ac7211b4bb-CDN/lu2bu7/820016/12ta74/d176f0986478cc64f24226b3d20c140d/_/download/contextbatch/css/com.atlassian.jira.projects.sidebar.init,-_super,-project.issue.navigator,-jira.view.issue/batch.css?jira.create.linked.issue=true","startTime":167.70000004768372,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":167.70000004768372,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":313.5,"responseStart":0,"secureConnectionStart":0},{"duration":145.90000009536743,"initiatorType":"script","name":"https://jira.mariadb.org/s/3339d87fa2538a859872f2df449bf8d0-CDN/lu2bu7/820016/12ta74/d176f0986478cc64f24226b3d20c140d/_/download/contextbatch/js/com.atlassian.jira.projects.sidebar.init,-_super,-project.issue.navigator,-jira.view.issue/batch.js?jira.create.linked.issue=true&locale=en","startTime":167.70000004768372,"connectEnd":167.70000004768372,"connectStart":167.70000004768372,"domainLookupEnd":167.70000004768372,"domainLookupStart":167.70000004768372,"fetchStart":167.70000004768372,"redirectEnd":0,"redirectStart":0,"requestStart":167.70000004768372,"responseEnd":313.60000014305115,"responseStart":313.60000014305115,"secureConnectionStart":167.70000004768372},{"duration":432.39999985694885,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bu7/820016/12ta74/1.0/_/download/batch/jira.webresources:bigpipe-js/jira.webresources:bigpipe-js.js","startTime":168.40000009536743,"connectEnd":168.40000009536743,"connectStart":168.40000009536743,"domainLookupEnd":168.40000009536743,"domainLookupStart":168.40000009536743,"fetchStart":168.40000009536743,"redirectEnd":0,"redirectStart":0,"requestStart":168.40000009536743,"responseEnd":600.7999999523163,"responseStart":600.7999999523163,"secureConnectionStart":168.40000009536743},{"duration":436.7000000476837,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bu7/820016/12ta74/1.0/_/download/batch/jira.webresources:bigpipe-init/jira.webresources:bigpipe-init.js","startTime":168.5,"connectEnd":168.5,"connectStart":168.5,"domainLookupEnd":168.5,"domainLookupStart":168.5,"fetchStart":168.5,"redirectEnd":0,"redirectStart":0,"requestStart":168.5,"responseEnd":605.2000000476837,"responseStart":605.2000000476837,"secureConnectionStart":168.5},{"duration":156.79999995231628,"initiatorType":"xmlhttprequest","name":"https://jira.mariadb.org/rest/webResources/1.0/resources","startTime":440.40000009536743,"connectEnd":440.40000009536743,"connectStart":440.40000009536743,"domainLookupEnd":440.40000009536743,"domainLookupStart":440.40000009536743,"fetchStart":440.40000009536743,"redirectEnd":0,"redirectStart":0,"requestStart":440.40000009536743,"responseEnd":597.2000000476837,"responseStart":597.2000000476837,"secureConnectionStart":440.40000009536743},{"duration":1.5,"initiatorType":"script","name":"https://www.google-analytics.com/analytics.js","startTime":607.9000000953674,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":607.9000000953674,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":609.4000000953674,"responseStart":0,"secureConnectionStart":0},{"duration":120,"initiatorType":"link","name":"https://jira.mariadb.org/s/d5715adaadd168a9002b108b2b039b50-CDN/lu2bu7/820016/12ta74/be4b45e9cec53099498fa61c8b7acba4/_/download/contextbatch/css/jira.project.sidebar,-_super,-project.issue.navigator,-jira.general,-jira.browse.project,-jira.view.issue,-jira.global,-atl.general,-com.atlassian.jira.projects.sidebar.init/batch.css?agile_global_admin_condition=true&jag=true&jira.create.linked.issue=true&slack-enabled=true","startTime":678.7999999523163,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":678.7999999523163,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":798.7999999523163,"responseStart":0,"secureConnectionStart":0},{"duration":120.5,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bu7/820016/12ta74/e65b778d185daf5aee24936755b43da6/_/download/contextbatch/js/browser-metrics-plugin.contrib,-_super,-project.issue.navigator,-jira.view.issue,-atl.general/batch.js?agile_global_admin_condition=true&jag=true&jira.create.linked.issue=true&slack-enabled=true","startTime":679.5,"connectEnd":679.5,"connectStart":679.5,"domainLookupEnd":679.5,"domainLookupStart":679.5,"fetchStart":679.5,"redirectEnd":0,"redirectStart":0,"requestStart":679.5,"responseEnd":800,"responseStart":800,"secureConnectionStart":679.5},{"duration":123.70000004768372,"initiatorType":"script","name":"https://jira.mariadb.org/s/f51ef5507eea4c158f257c66c93b2a3f-CDN/lu2bu7/820016/12ta74/be4b45e9cec53099498fa61c8b7acba4/_/download/contextbatch/js/jira.project.sidebar,-_super,-project.issue.navigator,-jira.general,-jira.browse.project,-jira.view.issue,-jira.global,-atl.general,-com.atlassian.jira.projects.sidebar.init/batch.js?agile_global_admin_condition=true&jag=true&jira.create.linked.issue=true&locale=en&slack-enabled=true","startTime":680,"connectEnd":680,"connectStart":680,"domainLookupEnd":680,"domainLookupStart":680,"fetchStart":680,"redirectEnd":0,"redirectStart":0,"requestStart":680,"responseEnd":803.7000000476837,"responseStart":803.7000000476837,"secureConnectionStart":680}],"fetchStart":0,"domainLookupStart":0,"domainLookupEnd":0,"connectStart":0,"connectEnd":0,"requestStart":18,"responseStart":160,"responseEnd":163,"domLoading":163,"domInteractive":842,"domContentLoadedEventStart":842,"domContentLoadedEventEnd":877,"domComplete":1106,"loadEventStart":1106,"loadEventEnd":1107,"userAgent":"Mozilla/5.0 AppleWebKit/537.36 (KHTML, like Gecko; compatible; ClaudeBot/1.0; +claudebot@anthropic.com)","marks":[{"name":"bigPipe.sidebar-id.start","time":804.7000000476837},{"name":"bigPipe.sidebar-id.end","time":805.4000000953674},{"name":"bigPipe.activity-panel-pipe-id.start","time":805.6000001430511},{"name":"bigPipe.activity-panel-pipe-id.end","time":806.6000001430511},{"name":"activityTabFullyLoaded","time":893.7000000476837}],"measures":[],"correlationId":"b4860a73e52489","effectiveType":"4g","downlink":9.1,"rtt":0,"serverDuration":76,"dbReadsTimeInMs":10,"dbConnsTimeInMs":15,"applicationHash":"9d11dbea5f4be3d4cc21f03a88dd11d8c8687422","experiments":[]}}
Bug
Major
Unfortunately the table this query targeted got dropped in the meanwhile, so I can't provide more optimizer traces for it. Let's keep the issue open though for the immediate future and I'll get back to you when we encounter this again.