The failed one in http://buildbot.askmonty.org/buildbot/builders/winx64-debug/builds/22164
commit 657fcdf430f39a3103dff51a6a2b2bd3 with the following stack trace
server!inline_mysql_cond_wait(struct st_mysql_cond * that = 0x00007ffb`ec653b68, struct st_mysql_mutex * mutex = 0x00007ffb`ec653b38, char * src_file = 0x00007ffb`ea98b140 "D:\winx64-debug\build\src\sql\log.cc", unsigned int src_line = 0x10f8) [D:\winx64-debug\build\src\include\mysql\psi\mysql_thread.h @ 1222]
|
server!MYSQL_BIN_LOG::reset_logs(class THD * thd = 0x00000165`d43d15c8, bool create_new_log = true, struct rpl_gtid * init_state = 0x00000000`00000000, unsigned int init_state_len = 0, unsigned long next_log_number = 0) [D:\winx64-debug\build\src\sql\log.cc @ 4345]
|
server!reset_master(class THD * thd = 0x00000165`d43d15c8, struct rpl_gtid * init_state = 0x00000000`00000000, unsigned int init_state_len = 0, unsigned long next_log_number = 0) [D:\winx64-debug\build\src\sql\sql_repl.cc @ 3966]
|
server!reload_acl_and_cache(class THD * thd = 0x00000165`d43d15c8, unsigned int64 options = 0x80, struct TABLE_LIST * tables = 0x00000000`00000000, int * write_to_binlog = 0x0000002a`c42fdc44) [D:\winx64-debug\build\src\sql\sql_reload.cc @ 362]
|
server!mysql_execute_command(class THD * thd = 0x00000165`d43d15c8) [D:\winx64-debug\build\src\sql\sql_parse.cc @ 5480]
|
server!mysql_parse(class THD * thd = 0x00000165`d43d15c8, char * rawbuf = 0x00000165`d44da280 "--- memory read error at address 0x00000165`d44da280 ---", unsigned int length = 0xc, class Parser_state * parser_state =
|
As it was never observed before the extra to 10.5 commits
need examination to clear out
possibility of missed out commit_checkpoint_notify_ha() invocation from innobase_mysql_log_notify() which is one of possibilities.
657fcdf430f and 7b1252c03d7 are rated as potentially relevant to the failure showdown.
A similar failure
main.mysqldump-max 'innodb' w2 [ fail ] timeout after 900 seconds
|
exists in http://buildbot.askmonty.org/buildbot/builders/win32-debug/builds/18570/steps/test/logs/stdio
Upon more analysis the most probable suspect
--- a/storage/innobase/handler/ha_innodb.cc
|
+++ b/storage/innobase/handler/ha_innodb.cc
|
@@ -4444,12 +4444,6 @@ innobase_mysql_log_notify(
|
struct pending_checkpoint * entry;
|
struct pending_checkpoint * last_ready;
|
|
- /* It is safe to do a quick check for NULL first without lock.
|
- Even if we should race, we will at most skip one checkpoint and
|
- take the next one, which is harmless. */
|
- if (!pending_checkpoint_list)
|
- return;
|
-
|
is identified. , to eliminate (the pasted block is from being tested fixes). Removal won't hurt performance in normal cases when binlog rotation is not frequent event (say not few in a second).
{"report":{"fcp":1725.9000000953674,"ttfb":314.90000009536743,"pageVisibility":"visible","entityId":94312,"key":"jira.project.issue.view-issue","isInitial":true,"threshold":1000,"elementTimings":{},"userDeviceMemory":8,"userDeviceProcessors":64,"apdex":0.5,"journeyId":"804d76be-eb95-4ca1-8990-a1f73833ba31","navigationType":0,"readyForUser":1978.6000000238419,"redirectCount":0,"resourceLoadedEnd":1423.2000000476837,"resourceLoadedStart":332,"resourceTiming":[{"duration":240.20000004768372,"initiatorType":"link","name":"https://jira.mariadb.org/s/2c21342762a6a02add1c328bed317ffd-CDN/lu2cib/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/css/_super/batch.css","startTime":332,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":332,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":572.2000000476837,"responseStart":0,"secureConnectionStart":0},{"duration":266.7000000476837,"initiatorType":"link","name":"https://jira.mariadb.org/s/7ebd35e77e471bc30ff0eba799ebc151-CDN/lu2cib/820016/12ta74/494e4c556ecbb29f90a3d3b4f09cb99c/_/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&whisper-enabled=true","startTime":332.2000000476837,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":332.2000000476837,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":598.9000000953674,"responseStart":0,"secureConnectionStart":0},{"duration":592.9000000953674,"initiatorType":"script","name":"https://jira.mariadb.org/s/0917945aaa57108d00c5076fea35e069-CDN/lu2cib/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/js/_super/batch.js?locale=en","startTime":332.5,"connectEnd":332.5,"connectStart":332.5,"domainLookupEnd":332.5,"domainLookupStart":332.5,"fetchStart":332.5,"redirectEnd":0,"redirectStart":0,"requestStart":610.3000000715256,"responseEnd":925.4000000953674,"responseStart":652.8000000715256,"secureConnectionStart":332.5},{"duration":904.3999999761581,"initiatorType":"script","name":"https://jira.mariadb.org/s/2d8175ec2fa4c816e8023260bd8c1786-CDN/lu2cib/820016/12ta74/494e4c556ecbb29f90a3d3b4f09cb99c/_/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&whisper-enabled=true","startTime":332.60000002384186,"connectEnd":332.60000002384186,"connectStart":332.60000002384186,"domainLookupEnd":332.60000002384186,"domainLookupStart":332.60000002384186,"fetchStart":332.60000002384186,"redirectEnd":0,"redirectStart":0,"requestStart":610,"responseEnd":1237,"responseStart":695.4000000953674,"secureConnectionStart":332.60000002384186},{"duration":328.6999999284744,"initiatorType":"script","name":"https://jira.mariadb.org/s/a9324d6758d385eb45c462685ad88f1d-CDN/lu2cib/820016/12ta74/c92c0caa9a024ae85b0ebdbed7fb4bd7/_/download/contextbatch/js/atl.global,-_super/batch.js?locale=en","startTime":332.8000000715256,"connectEnd":332.8000000715256,"connectStart":332.8000000715256,"domainLookupEnd":332.8000000715256,"domainLookupStart":332.8000000715256,"fetchStart":332.8000000715256,"redirectEnd":0,"redirectStart":0,"requestStart":610.5,"responseEnd":661.5,"responseStart":650.3000000715256,"secureConnectionStart":332.8000000715256},{"duration":337.3000000715256,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2cib/820016/12ta74/1.0/_/download/batch/jira.webresources:calendar-en/jira.webresources:calendar-en.js","startTime":333,"connectEnd":333,"connectStart":333,"domainLookupEnd":333,"domainLookupStart":333,"fetchStart":333,"redirectEnd":0,"redirectStart":0,"requestStart":611.1000000238419,"responseEnd":670.3000000715256,"responseStart":658.2000000476837,"secureConnectionStart":333},{"duration":336.89999997615814,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2cib/820016/12ta74/1.0/_/download/batch/jira.webresources:calendar-localisation-moment/jira.webresources:calendar-localisation-moment.js","startTime":333.10000002384186,"connectEnd":333.10000002384186,"connectStart":333.10000002384186,"domainLookupEnd":333.10000002384186,"domainLookupStart":333.10000002384186,"fetchStart":333.10000002384186,"redirectEnd":0,"redirectStart":0,"requestStart":610.6000000238419,"responseEnd":670,"responseStart":657.5,"secureConnectionStart":333.10000002384186},{"duration":269,"initiatorType":"link","name":"https://jira.mariadb.org/s/b04b06a02d1959df322d9cded3aeecc1-CDN/lu2cib/820016/12ta74/a2ff6aa845ffc9a1d22fe23d9ee791fc/_/download/contextbatch/css/jira.global.look-and-feel,-_super/batch.css","startTime":333.3000000715256,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":333.3000000715256,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":602.3000000715256,"responseStart":0,"secureConnectionStart":0},{"duration":337.5,"initiatorType":"script","name":"https://jira.mariadb.org/rest/api/1.0/shortcuts/820016/47140b6e0a9bc2e4913da06536125810/shortcuts.js?context=issuenavigation&context=issueaction","startTime":333.5,"connectEnd":333.5,"connectStart":333.5,"domainLookupEnd":333.5,"domainLookupStart":333.5,"fetchStart":333.5,"redirectEnd":0,"redirectStart":0,"requestStart":611.3000000715256,"responseEnd":671,"responseStart":658.7000000476837,"secureConnectionStart":333.5},{"duration":269.2000000476837,"initiatorType":"link","name":"https://jira.mariadb.org/s/3ac36323ba5e4eb0af2aa7ac7211b4bb-CDN/lu2cib/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":333.7000000476837,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":333.7000000476837,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":602.9000000953674,"responseStart":0,"secureConnectionStart":0},{"duration":338,"initiatorType":"script","name":"https://jira.mariadb.org/s/5d5e8fe91fbc506585e83ea3b62ccc4b-CDN/lu2cib/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":333.8000000715256,"connectEnd":333.8000000715256,"connectStart":333.8000000715256,"domainLookupEnd":333.8000000715256,"domainLookupStart":333.8000000715256,"fetchStart":333.8000000715256,"redirectEnd":0,"redirectStart":0,"requestStart":611.4000000953674,"responseEnd":671.8000000715256,"responseStart":662.3000000715256,"secureConnectionStart":333.8000000715256},{"duration":1044.7999999523163,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2cib/820016/12ta74/1.0/_/download/batch/jira.webresources:bigpipe-js/jira.webresources:bigpipe-js.js","startTime":343.90000009536743,"connectEnd":343.90000009536743,"connectStart":343.90000009536743,"domainLookupEnd":343.90000009536743,"domainLookupStart":343.90000009536743,"fetchStart":343.90000009536743,"redirectEnd":0,"redirectStart":0,"requestStart":981.8000000715256,"responseEnd":1388.7000000476837,"responseStart":1349.1000000238419,"secureConnectionStart":343.90000009536743},{"duration":1079.2999999523163,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2cib/820016/12ta74/1.0/_/download/batch/jira.webresources:bigpipe-init/jira.webresources:bigpipe-init.js","startTime":343.90000009536743,"connectEnd":343.90000009536743,"connectStart":343.90000009536743,"domainLookupEnd":343.90000009536743,"domainLookupStart":343.90000009536743,"fetchStart":343.90000009536743,"redirectEnd":0,"redirectStart":0,"requestStart":1314,"responseEnd":1423.2000000476837,"responseStart":1399.5,"secureConnectionStart":343.90000009536743},{"duration":350.8000000715256,"initiatorType":"xmlhttprequest","name":"https://jira.mariadb.org/rest/webResources/1.0/resources","startTime":1073.6000000238419,"connectEnd":1073.6000000238419,"connectStart":1073.6000000238419,"domainLookupEnd":1073.6000000238419,"domainLookupStart":1073.6000000238419,"fetchStart":1073.6000000238419,"redirectEnd":0,"redirectStart":0,"requestStart":1314.4000000953674,"responseEnd":1424.4000000953674,"responseStart":1418.7000000476837,"secureConnectionStart":1073.6000000238419}],"fetchStart":0,"domainLookupStart":0,"domainLookupEnd":0,"connectStart":0,"connectEnd":0,"requestStart":104,"responseStart":315,"responseEnd":341,"domLoading":330,"domInteractive":2114,"domContentLoadedEventStart":2114,"domContentLoadedEventEnd":2205,"domComplete":3014,"loadEventStart":3015,"loadEventEnd":3019,"userAgent":"Mozilla/5.0 AppleWebKit/537.36 (KHTML, like Gecko; compatible; ClaudeBot/1.0; +claudebot@anthropic.com)","marks":[{"name":"bigPipe.sidebar-id.start","time":2074.4000000953674},{"name":"bigPipe.sidebar-id.end","time":2075.2000000476837},{"name":"bigPipe.activity-panel-pipe-id.start","time":2075.4000000953674},{"name":"bigPipe.activity-panel-pipe-id.end","time":2077.5},{"name":"activityTabFullyLoaded","time":2229.8000000715256}],"measures":[],"correlationId":"949dcf579e839","effectiveType":"4g","downlink":10,"rtt":0,"serverDuration":124,"dbReadsTimeInMs":29,"dbConnsTimeInMs":40,"applicationHash":"9d11dbea5f4be3d4cc21f03a88dd11d8c8687422","experiments":[]}}
The probability of hang could be improved by running with innodb_force_recovery=2 (to prevent the purge of InnoDB transaction history from running). Any ‘extra’ writes to the redo log (outside transaction commit) could ‘rescue’ the hang.