sanja noted that during the execution of the test innodb.innodb-32k-crash some debug assertions that had been added together with the fix of MDEV-31354 to the function log_sort_flush_list() are failing rather often, like this:
10.6 753e7d6d7ce7770d3c98beb6fdcb97e0e8d1ec9f
|
innodb.innodb-32k-crash w18 [ fail ]
|
Test ended at 2024-10-01 10:31:25
|
…
|
2024-10-01 10:31:25 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=1299959,3973825
|
2024-10-01 10:31:25 0 [Note] InnoDB: 1 transaction(s) which must be rolled back or cleaned up in total 3 row operations to undo
|
2024-10-01 10:31:25 0 [Note] InnoDB: Trx id counter is 225
|
2024-10-01 10:31:25 0 [Note] InnoDB: To recover: 658 pages
|
mariadbd: /home/buildbot/amd64-ubuntu-2204-debug-ps/build/storage/innobase/log/log0recv.cc:3658: log_sort_flush_list()::<lambda(const buf_page_t*, const buf_page_t*)>: Assertion `l > 2' failed.
|
I was able to reproduce this. In the core dump that I analyzed, all 7 members of buf_pool.flush_list carried oldest_modification()==1, that is, the pages had been written back to the file system.
As noted in MDEV-31354, starting with MDEV-25113 it is possible that the buf_page_t::oldest_modification() will be updated to 1 by a thread that is not holding buf_pool.flush_list_mutex. The debug assertions on LSN being above 2 must be revised accordingly. As a slight optimization, when we are copying the sorted list back to buf_pool.flush_list, we can omit such blocks.
The test innodb.innodb-32k-crash also started to fail in another way in 10.6 but not later versions, due to a bogus debug assertion that was added to recv_recovery_from_checkpoint_start() in MDEV-34830:
ut_ad(log_sys.get_lsn() >= recv_sys.scanned_lsn);
|
This assertion may fail when the last mini-transaction in the log was not completely written. In that case, the recv_sys.scanned_lsn could be a few 512-byte blocks ahead of recv_sys.recovered_lsn, which is what matters. In MDEV-14425, these fields were replaced by recv_sys.lsn and there is no log block layer anymore; each mini-transaction is a logical log block on its own.
{"report":{"fcp":1752,"ttfb":449.2999999523163,"pageVisibility":"visible","entityId":131299,"key":"jira.project.issue.view-issue","isInitial":true,"threshold":1000,"elementTimings":{},"userDeviceMemory":8,"userDeviceProcessors":64,"apdex":0.5,"journeyId":"1cb20c50-0276-4b04-8192-8c3b4196071b","navigationType":0,"readyForUser":1852.5,"redirectCount":0,"resourceLoadedEnd":1901.3999999761581,"resourceLoadedStart":454.39999997615814,"resourceTiming":[{"duration":727.7000000476837,"initiatorType":"link","name":"https://jira.mariadb.org/s/2c21342762a6a02add1c328bed317ffd-CDN/lu2cib/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/css/_super/batch.css","startTime":454.39999997615814,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":454.39999997615814,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1182.1000000238419,"responseStart":0,"secureConnectionStart":0},{"duration":727.7999999523163,"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":454.60000002384186,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":454.60000002384186,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1182.3999999761581,"responseStart":0,"secureConnectionStart":0},{"duration":733,"initiatorType":"script","name":"https://jira.mariadb.org/s/0917945aaa57108d00c5076fea35e069-CDN/lu2cib/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/js/_super/batch.js?locale=en","startTime":454.7999999523163,"connectEnd":454.7999999523163,"connectStart":454.7999999523163,"domainLookupEnd":454.7999999523163,"domainLookupStart":454.7999999523163,"fetchStart":454.7999999523163,"redirectEnd":0,"redirectStart":0,"requestStart":454.7999999523163,"responseEnd":1187.7999999523163,"responseStart":1187.7999999523163,"secureConnectionStart":454.7999999523163},{"duration":774.1000000238419,"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":455,"connectEnd":455,"connectStart":455,"domainLookupEnd":455,"domainLookupStart":455,"fetchStart":455,"redirectEnd":0,"redirectStart":0,"requestStart":455,"responseEnd":1229.1000000238419,"responseStart":1229.1000000238419,"secureConnectionStart":455},{"duration":778.5999999046326,"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":455.10000002384186,"connectEnd":455.10000002384186,"connectStart":455.10000002384186,"domainLookupEnd":455.10000002384186,"domainLookupStart":455.10000002384186,"fetchStart":455.10000002384186,"redirectEnd":0,"redirectStart":0,"requestStart":455.10000002384186,"responseEnd":1233.6999999284744,"responseStart":1233.6000000238419,"secureConnectionStart":455.10000002384186},{"duration":778.7999999523163,"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":455.39999997615814,"connectEnd":455.39999997615814,"connectStart":455.39999997615814,"domainLookupEnd":455.39999997615814,"domainLookupStart":455.39999997615814,"fetchStart":455.39999997615814,"redirectEnd":0,"redirectStart":0,"requestStart":455.39999997615814,"responseEnd":1234.1999999284744,"responseStart":1234.1999999284744,"secureConnectionStart":455.39999997615814},{"duration":779.2999999523163,"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":455.5,"connectEnd":455.5,"connectStart":455.5,"domainLookupEnd":455.5,"domainLookupStart":455.5,"fetchStart":455.5,"redirectEnd":0,"redirectStart":0,"requestStart":455.5,"responseEnd":1234.7999999523163,"responseStart":1234.7999999523163,"secureConnectionStart":455.5},{"duration":815.4000000953674,"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":455.6999999284744,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":455.6999999284744,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1271.1000000238419,"responseStart":0,"secureConnectionStart":0},{"duration":779.8999999761581,"initiatorType":"script","name":"https://jira.mariadb.org/rest/api/1.0/shortcuts/820016/47140b6e0a9bc2e4913da06536125810/shortcuts.js?context=issuenavigation&context=issueaction","startTime":455.89999997615814,"connectEnd":455.89999997615814,"connectStart":455.89999997615814,"domainLookupEnd":455.89999997615814,"domainLookupStart":455.89999997615814,"fetchStart":455.89999997615814,"redirectEnd":0,"redirectStart":0,"requestStart":455.89999997615814,"responseEnd":1235.7999999523163,"responseStart":1235.7999999523163,"secureConnectionStart":455.89999997615814},{"duration":815.1000000238419,"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":456,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":456,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1271.1000000238419,"responseStart":0,"secureConnectionStart":0},{"duration":780.4000000953674,"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":456.1999999284744,"connectEnd":456.1999999284744,"connectStart":456.1999999284744,"domainLookupEnd":456.1999999284744,"domainLookupStart":456.1999999284744,"fetchStart":456.1999999284744,"redirectEnd":0,"redirectStart":0,"requestStart":456.1999999284744,"responseEnd":1236.6000000238419,"responseStart":1236.6000000238419,"secureConnectionStart":456.1999999284744},{"duration":975.2000000476837,"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":462.89999997615814,"connectEnd":462.89999997615814,"connectStart":462.89999997615814,"domainLookupEnd":462.89999997615814,"domainLookupStart":462.89999997615814,"fetchStart":462.89999997615814,"redirectEnd":0,"redirectStart":0,"requestStart":462.89999997615814,"responseEnd":1438.1000000238419,"responseStart":1438.1000000238419,"secureConnectionStart":462.89999997615814},{"duration":1422.7000000476837,"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":478.6999999284744,"connectEnd":478.6999999284744,"connectStart":478.6999999284744,"domainLookupEnd":478.6999999284744,"domainLookupStart":478.6999999284744,"fetchStart":478.6999999284744,"redirectEnd":0,"redirectStart":0,"requestStart":478.6999999284744,"responseEnd":1901.3999999761581,"responseStart":1901.3999999761581,"secureConnectionStart":478.6999999284744},{"duration":410.7000000476837,"initiatorType":"xmlhttprequest","name":"https://jira.mariadb.org/rest/webResources/1.0/resources","startTime":1476.1999999284744,"connectEnd":1476.1999999284744,"connectStart":1476.1999999284744,"domainLookupEnd":1476.1999999284744,"domainLookupStart":1476.1999999284744,"fetchStart":1476.1999999284744,"redirectEnd":0,"redirectStart":0,"requestStart":1476.1999999284744,"responseEnd":1886.8999999761581,"responseStart":1886.8999999761581,"secureConnectionStart":1476.1999999284744}],"fetchStart":0,"domainLookupStart":0,"domainLookupEnd":0,"connectStart":0,"connectEnd":0,"requestStart":114,"responseStart":449,"responseEnd":478,"domLoading":452,"domInteractive":1979,"domContentLoadedEventStart":1979,"domContentLoadedEventEnd":2021,"domComplete":3367,"loadEventStart":3367,"loadEventEnd":3369,"userAgent":"Mozilla/5.0 AppleWebKit/537.36 (KHTML, like Gecko; compatible; ClaudeBot/1.0; +claudebot@anthropic.com)","marks":[{"name":"bigPipe.sidebar-id.start","time":1947.8999999761581},{"name":"bigPipe.sidebar-id.end","time":1948.7999999523163},{"name":"bigPipe.activity-panel-pipe-id.start","time":1949},{"name":"bigPipe.activity-panel-pipe-id.end","time":1952.8999999761581},{"name":"activityTabFullyLoaded","time":2033}],"measures":[],"correlationId":"a91c7139ac37f8","effectiveType":"4g","downlink":9.2,"rtt":0,"serverDuration":106,"dbReadsTimeInMs":19,"dbConnsTimeInMs":30,"applicationHash":"9d11dbea5f4be3d4cc21f03a88dd11d8c8687422","experiments":[]}}