http://buildbot.askmonty.org/buildbot/builders/kvm-bintar-quantal-amd64/builds/6902/steps/test/logs/stdio
encryption.innodb-redo-badkey 'cbc,innodb' w4 [ fail ] Found warnings/errors in server log file!
|
Test ended at 2017-08-17 10:04:15
|
line
|
2017-08-17 10:04:12 140553760634624 [ERROR] InnoDB: The page [page id: space=7, page number=3] in file './test/t4.ibd' cannot be decrypted.
|
2017-08-17 10:04:12 140553760634624 [ERROR] InnoDB: The page [page id: space=6, page number=3] in file './test/t3.ibd' cannot be decrypted.
|
2017-08-17 10:04:12 140553760634624 [ERROR] InnoDB: Unable to decompress ./test/t2.ibd[page id: space=5, page number=3]
|
2017-08-17 10:04:12 140553760634624 [ERROR] InnoDB: Database page corruption on disk or a failed file read of tablespace test/t2 page [page id: space=5, page number=3]. You may have to recover from a backup.
|
2017-08-17 10:04:12 140553760634624 [ERROR] InnoDB: Unable to decompress ./test/t1.ibd[page id: space=4, page number=3]
|
2017-08-17 10:04:12 140553760634624 [ERROR] InnoDB: Database page corruption on disk or a failed file read of tablespace test/t1 page [page id: space=4, page number=3]. You may have to recover from a backup.
|
^ Found warnings in /usr/local/mariadb-10.3.1-linux-x86_64/mysql-test/var/4/log/mysqld.1.err
|
|
{"report":{"fcp":1006,"ttfb":272.10000014305115,"pageVisibility":"visible","entityId":62732,"key":"jira.project.issue.view-issue","isInitial":true,"threshold":1000,"elementTimings":{},"userDeviceMemory":8,"userDeviceProcessors":64,"apdex":0.5,"journeyId":"ef6251f5-a58d-4f3d-af08-ba05091e55e7","navigationType":0,"readyForUser":1084.7999999523163,"redirectCount":0,"resourceLoadedEnd":1696.1000001430511,"resourceLoadedStart":277.7000000476837,"resourceTiming":[{"duration":44.59999990463257,"initiatorType":"link","name":"https://jira.mariadb.org/s/2c21342762a6a02add1c328bed317ffd-CDN/lu2cib/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/css/_super/batch.css","startTime":277.7000000476837,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":277.7000000476837,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":322.2999999523163,"responseStart":0,"secureConnectionStart":0},{"duration":49,"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":277.90000009536743,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":277.90000009536743,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":326.90000009536743,"responseStart":0,"secureConnectionStart":0},{"duration":156.60000014305115,"initiatorType":"script","name":"https://jira.mariadb.org/s/0917945aaa57108d00c5076fea35e069-CDN/lu2cib/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/js/_super/batch.js?locale=en","startTime":278,"connectEnd":278,"connectStart":278,"domainLookupEnd":278,"domainLookupStart":278,"fetchStart":278,"redirectEnd":0,"redirectStart":0,"requestStart":278,"responseEnd":434.60000014305115,"responseStart":434.60000014305115,"secureConnectionStart":278},{"duration":404.90000009536743,"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":278.2000000476837,"connectEnd":278.2000000476837,"connectStart":278.2000000476837,"domainLookupEnd":278.2000000476837,"domainLookupStart":278.2000000476837,"fetchStart":278.2000000476837,"redirectEnd":0,"redirectStart":0,"requestStart":278.2000000476837,"responseEnd":683.1000001430511,"responseStart":683.1000001430511,"secureConnectionStart":278.2000000476837},{"duration":408.7000000476837,"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":278.40000009536743,"connectEnd":278.40000009536743,"connectStart":278.40000009536743,"domainLookupEnd":278.40000009536743,"domainLookupStart":278.40000009536743,"fetchStart":278.40000009536743,"redirectEnd":0,"redirectStart":0,"requestStart":278.40000009536743,"responseEnd":687.1000001430511,"responseStart":687.1000001430511,"secureConnectionStart":278.40000009536743},{"duration":409.10000014305115,"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":278.5,"connectEnd":278.5,"connectStart":278.5,"domainLookupEnd":278.5,"domainLookupStart":278.5,"fetchStart":278.5,"redirectEnd":0,"redirectStart":0,"requestStart":278.5,"responseEnd":687.6000001430511,"responseStart":687.6000001430511,"secureConnectionStart":278.5},{"duration":409.2000000476837,"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":278.7000000476837,"connectEnd":278.7000000476837,"connectStart":278.7000000476837,"domainLookupEnd":278.7000000476837,"domainLookupStart":278.7000000476837,"fetchStart":278.7000000476837,"redirectEnd":0,"redirectStart":0,"requestStart":278.7000000476837,"responseEnd":687.9000000953674,"responseStart":687.9000000953674,"secureConnectionStart":278.7000000476837},{"duration":410.30000019073486,"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":278.7999999523163,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":278.7999999523163,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":689.1000001430511,"responseStart":0,"secureConnectionStart":0},{"duration":409.2999999523163,"initiatorType":"script","name":"https://jira.mariadb.org/rest/api/1.0/shortcuts/820016/47140b6e0a9bc2e4913da06536125810/shortcuts.js?context=issuenavigation&context=issueaction","startTime":279,"connectEnd":279,"connectStart":279,"domainLookupEnd":279,"domainLookupStart":279,"fetchStart":279,"redirectEnd":0,"redirectStart":0,"requestStart":279,"responseEnd":688.2999999523163,"responseStart":688.2999999523163,"secureConnectionStart":279},{"duration":411.2999999523163,"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":279.2000000476837,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":279.2000000476837,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":690.5,"responseStart":0,"secureConnectionStart":0},{"duration":410.39999985694885,"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":279.40000009536743,"connectEnd":279.40000009536743,"connectStart":279.40000009536743,"domainLookupEnd":279.40000009536743,"domainLookupStart":279.40000009536743,"fetchStart":279.40000009536743,"redirectEnd":0,"redirectStart":0,"requestStart":279.40000009536743,"responseEnd":689.7999999523163,"responseStart":689.7999999523163,"secureConnectionStart":279.40000009536743},{"duration":713.9000000953674,"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":285.7999999523163,"connectEnd":285.7999999523163,"connectStart":285.7999999523163,"domainLookupEnd":285.7999999523163,"domainLookupStart":285.7999999523163,"fetchStart":285.7999999523163,"redirectEnd":0,"redirectStart":0,"requestStart":285.7999999523163,"responseEnd":999.7000000476837,"responseStart":999.7000000476837,"secureConnectionStart":285.7999999523163},{"duration":1410.2000000476837,"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":285.90000009536743,"connectEnd":285.90000009536743,"connectStart":285.90000009536743,"domainLookupEnd":285.90000009536743,"domainLookupStart":285.90000009536743,"fetchStart":285.90000009536743,"redirectEnd":0,"redirectStart":0,"requestStart":285.90000009536743,"responseEnd":1696.1000001430511,"responseStart":1696.1000001430511,"secureConnectionStart":285.90000009536743},{"duration":413.09999990463257,"initiatorType":"xmlhttprequest","name":"https://jira.mariadb.org/rest/webResources/1.0/resources","startTime":585.4000000953674,"connectEnd":585.4000000953674,"connectStart":585.4000000953674,"domainLookupEnd":585.4000000953674,"domainLookupStart":585.4000000953674,"fetchStart":585.4000000953674,"redirectEnd":0,"redirectStart":0,"requestStart":585.4000000953674,"responseEnd":998.5,"responseStart":998.5,"secureConnectionStart":585.4000000953674},{"duration":753.7000000476837,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2cib/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&whisper-enabled=true","startTime":1022.7999999523163,"connectEnd":1022.7999999523163,"connectStart":1022.7999999523163,"domainLookupEnd":1022.7999999523163,"domainLookupStart":1022.7999999523163,"fetchStart":1022.7999999523163,"redirectEnd":0,"redirectStart":0,"requestStart":1022.7999999523163,"responseEnd":1776.5,"responseStart":1776.5,"secureConnectionStart":1022.7999999523163}],"fetchStart":1,"domainLookupStart":1,"domainLookupEnd":1,"connectStart":1,"connectEnd":1,"requestStart":88,"responseStart":273,"responseEnd":277,"domLoading":277,"domInteractive":1722,"domContentLoadedEventStart":1722,"domContentLoadedEventEnd":1765,"domComplete":2064,"loadEventStart":2064,"loadEventEnd":2065,"userAgent":"Mozilla/5.0 AppleWebKit/537.36 (KHTML, like Gecko; compatible; ClaudeBot/1.0; +claudebot@anthropic.com)","marks":[{"name":"bigPipe.sidebar-id.start","time":1701.1000001430511},{"name":"bigPipe.sidebar-id.end","time":1702},{"name":"bigPipe.activity-panel-pipe-id.start","time":1702.1000001430511},{"name":"bigPipe.activity-panel-pipe-id.end","time":1703.4000000953674},{"name":"activityTabFullyLoaded","time":1777.4000000953674}],"measures":[],"correlationId":"8a27fccfbe5fc8","effectiveType":"4g","downlink":9.8,"rtt":0,"serverDuration":109,"dbReadsTimeInMs":18,"dbConnsTimeInMs":28,"applicationHash":"9d11dbea5f4be3d4cc21f03a88dd11d8c8687422","experiments":[]}}
Bug
Major
I believe I observed a similar failure when testing
MDEV-13536.I did touch this test in my work-around for MDEV-13542, maybe in vain, because this test is abruptly killing the server, not doing a clean shutdown and restart.
Looking at the server error log
http://buildbot.askmonty.org/buildbot/builders/kvm-bintar-quantal-amd64/builds/6902/steps/test/logs/mysqld.1.err.4
I determined that this crash occurs on the first restart, after no_checkpoint_end.inc killed the server. This server startup is expected to fail, and it did fail:
--source ../../suite/innodb/include/no_checkpoint_end.inc--echo # restart--error 1-- source include/start_mysqld.incThe only problem here is that we got an unexpected message about decompression failure. There is only a suppression about a decryption failure:
call mtr.add_suppression("InnoDB: The page \[page id: space=[1-9][0-9]*, page number=[0-9]+\] in file test/t[1-4] cannot be decrypted");The proper solution would seem to be to add the missing suppression.
The reason why this message is being issued should be that with
MDEV-13536(andMDEV-12288), purge now has more work to do, and thus more redo log records can be emitted before the crash. Purge would clear the DB_TRX_ID columns of each INSERT. BeforeMDEV-12288, purge did not have any access to any undo log generated by INSERT to an empty table.I was not able to repeat the failure locally, even with some small tricks.