Hi Team,
We upgraded from 10.11.8 to 10.11.10 two weeks ago and since then mariabackup keeps failing with the following logs.
[00] 2025-02-24 00:41:42 Waiting for log copy thread to read lsn 160568393706001
|
[00] 2025-02-24 00:41:43 Retrying read of log at LSN=160515356496404
|
[00] 2025-02-24 00:41:44 Retrying read of log at LSN=160515356496404
|
[00] 2025-02-24 00:41:45 Retrying read of log at LSN=160515356496404
|
[00] 2025-02-24 00:41:46 Retrying read of log at LSN=160515356496404
|
[00] 2025-02-24 00:41:47 Retrying read of log at LSN=160515356496404
|
[00] 2025-02-24 00:41:47 Was only able to copy log from 160487092534834 to 160515356496404, not 160568393706001; try increasing innodb_log_file_size
|
mariabackup: Stopping log copying thread.
|
I judged that this was caused by a small innodb_log_file_size value, so I tested to change it to an appropriate value and got the following results.
- MariaDB engine ver : 10.11.10 / mariabackup engine ver : 10.11.10
innodb_log_file_size = 1G - failed
innodb_log_file_size = 4G - failed
innodb_log_file_size = 8G - failed
- MariaDB engine ver : 10.11.8 / mariabackup engine ver : 10.11.8
innodb_log_file_size = 1G - success
innodb_log_file_size = 4G - success
- MariaDB engine ver : 10.11.10 / mariabackup engine ver : 10.11.8
innodb_log_file_size = 1G - success
Is this a new bug different from MDEV-34062?
I would like to know if there is any impact on the acceptability of backups from engine 10.11.10 to mariabackup 10.11.8 in production environments.
Thanks and Regard.
{"report":{"fcp":1718.4000000953674,"ttfb":413.19999980926514,"pageVisibility":"visible","entityId":132989,"key":"jira.project.issue.view-issue","isInitial":true,"threshold":1000,"elementTimings":{},"userDeviceMemory":8,"userDeviceProcessors":64,"apdex":0.5,"journeyId":"5f4a8713-6c67-485e-ad9b-dbe3e298863d","navigationType":0,"readyForUser":1838.1999998092651,"redirectCount":0,"resourceLoadedEnd":2136.9000000953674,"resourceLoadedStart":438.7999997138977,"resourceTiming":[{"duration":707.6000003814697,"initiatorType":"link","name":"https://jira.mariadb.org/s/2c21342762a6a02add1c328bed317ffd-CDN/lu2cib/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/css/_super/batch.css","startTime":438.7999997138977,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":438.7999997138977,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1146.4000000953674,"responseStart":0,"secureConnectionStart":0},{"duration":707.5999999046326,"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":439.09999990463257,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":439.09999990463257,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1146.6999998092651,"responseStart":0,"secureConnectionStart":0},{"duration":717.2000002861023,"initiatorType":"script","name":"https://jira.mariadb.org/s/0917945aaa57108d00c5076fea35e069-CDN/lu2cib/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/js/_super/batch.js?locale=en","startTime":439.2999997138977,"connectEnd":439.2999997138977,"connectStart":439.2999997138977,"domainLookupEnd":439.2999997138977,"domainLookupStart":439.2999997138977,"fetchStart":439.2999997138977,"redirectEnd":0,"redirectStart":0,"requestStart":439.2999997138977,"responseEnd":1156.5,"responseStart":1156.5,"secureConnectionStart":439.2999997138977},{"duration":767.5999999046326,"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":439.5,"connectEnd":439.5,"connectStart":439.5,"domainLookupEnd":439.5,"domainLookupStart":439.5,"fetchStart":439.5,"redirectEnd":0,"redirectStart":0,"requestStart":439.5,"responseEnd":1207.0999999046326,"responseStart":1207.0999999046326,"secureConnectionStart":439.5},{"duration":773.1999998092651,"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":439.90000009536743,"connectEnd":439.90000009536743,"connectStart":439.90000009536743,"domainLookupEnd":439.90000009536743,"domainLookupStart":439.90000009536743,"fetchStart":439.90000009536743,"redirectEnd":0,"redirectStart":0,"requestStart":439.90000009536743,"responseEnd":1213.0999999046326,"responseStart":1213,"secureConnectionStart":439.90000009536743},{"duration":774,"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":440,"connectEnd":440,"connectStart":440,"domainLookupEnd":440,"domainLookupStart":440,"fetchStart":440,"redirectEnd":0,"redirectStart":0,"requestStart":440,"responseEnd":1214,"responseStart":1214,"secureConnectionStart":440},{"duration":774.7000002861023,"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":440.19999980926514,"connectEnd":440.19999980926514,"connectStart":440.19999980926514,"domainLookupEnd":440.19999980926514,"domainLookupStart":440.19999980926514,"fetchStart":440.19999980926514,"redirectEnd":0,"redirectStart":0,"requestStart":440.19999980926514,"responseEnd":1214.9000000953674,"responseStart":1214.9000000953674,"secureConnectionStart":440.19999980926514},{"duration":889.5,"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":440.2999997138977,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":440.2999997138977,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1329.7999997138977,"responseStart":0,"secureConnectionStart":0},{"duration":775.9000000953674,"initiatorType":"script","name":"https://jira.mariadb.org/rest/api/1.0/shortcuts/820016/47140b6e0a9bc2e4913da06536125810/shortcuts.js?context=issuenavigation&context=issueaction","startTime":440.5,"connectEnd":440.5,"connectStart":440.5,"domainLookupEnd":440.5,"domainLookupStart":440.5,"fetchStart":440.5,"redirectEnd":0,"redirectStart":0,"requestStart":440.5,"responseEnd":1216.4000000953674,"responseStart":1216.4000000953674,"secureConnectionStart":440.5},{"duration":889.3000001907349,"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":440.59999990463257,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":440.59999990463257,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1329.9000000953674,"responseStart":0,"secureConnectionStart":0},{"duration":776.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":440.7999997138977,"connectEnd":440.7999997138977,"connectStart":440.7999997138977,"domainLookupEnd":440.7999997138977,"domainLookupStart":440.7999997138977,"fetchStart":440.7999997138977,"redirectEnd":0,"redirectStart":0,"requestStart":440.7999997138977,"responseEnd":1217.1999998092651,"responseStart":1217.1999998092651,"secureConnectionStart":440.7999997138977},{"duration":1406.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":442,"connectEnd":442,"connectStart":442,"domainLookupEnd":442,"domainLookupStart":442,"fetchStart":442,"redirectEnd":0,"redirectStart":0,"requestStart":442,"responseEnd":1848.9000000953674,"responseStart":1848.9000000953674,"secureConnectionStart":442},{"duration":1667.3000001907349,"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":469.59999990463257,"connectEnd":469.59999990463257,"connectStart":469.59999990463257,"domainLookupEnd":469.59999990463257,"domainLookupStart":469.59999990463257,"fetchStart":469.59999990463257,"redirectEnd":0,"redirectStart":0,"requestStart":469.59999990463257,"responseEnd":2136.9000000953674,"responseStart":2136.9000000953674,"secureConnectionStart":469.59999990463257},{"duration":493.3999996185303,"initiatorType":"xmlhttprequest","name":"https://jira.mariadb.org/rest/webResources/1.0/resources","startTime":1355.9000000953674,"connectEnd":1355.9000000953674,"connectStart":1355.9000000953674,"domainLookupEnd":1355.9000000953674,"domainLookupStart":1355.9000000953674,"fetchStart":1355.9000000953674,"redirectEnd":0,"redirectStart":0,"requestStart":1355.9000000953674,"responseEnd":1849.2999997138977,"responseStart":1849.2999997138977,"secureConnectionStart":1355.9000000953674},{"duration":498.90000009536743,"initiatorType":"script","name":"https://www.google-analytics.com/analytics.js","startTime":1690.7999997138977,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":1690.7999997138977,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":2189.699999809265,"responseStart":0,"secureConnectionStart":0},{"duration":374.30000019073486,"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":1891.1999998092651,"connectEnd":1891.1999998092651,"connectStart":1891.1999998092651,"domainLookupEnd":1891.1999998092651,"domainLookupStart":1891.1999998092651,"fetchStart":1891.1999998092651,"redirectEnd":0,"redirectStart":0,"requestStart":1891.1999998092651,"responseEnd":2265.5,"responseStart":2265.4000000953674,"secureConnectionStart":1891.1999998092651},{"duration":380.7000002861023,"initiatorType":"script","name":"https://jira.mariadb.org/s/097ae97cb8fbec7d6ea4bbb1f26955b9-CDN/lu2cib/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&whisper-enabled=true","startTime":1891.7999997138977,"connectEnd":1891.7999997138977,"connectStart":1891.7999997138977,"domainLookupEnd":1891.7999997138977,"domainLookupStart":1891.7999997138977,"fetchStart":1891.7999997138977,"redirectEnd":0,"redirectStart":0,"requestStart":1891.7999997138977,"responseEnd":2272.5,"responseStart":2272.5,"secureConnectionStart":1891.7999997138977}],"fetchStart":0,"domainLookupStart":0,"domainLookupEnd":0,"connectStart":0,"connectEnd":0,"requestStart":140,"responseStart":413,"responseEnd":469,"domLoading":418,"domInteractive":2194,"domContentLoadedEventStart":2194,"domContentLoadedEventEnd":2257,"domComplete":2946,"loadEventStart":2946,"loadEventEnd":2947,"userAgent":"Mozilla/5.0 AppleWebKit/537.36 (KHTML, like Gecko; compatible; ClaudeBot/1.0; +claudebot@anthropic.com)","marks":[{"name":"bigPipe.sidebar-id.start","time":2139.4000000953674},{"name":"bigPipe.sidebar-id.end","time":2140.2999997138977},{"name":"bigPipe.activity-panel-pipe-id.start","time":2140.4000000953674},{"name":"bigPipe.activity-panel-pipe-id.end","time":2147},{"name":"activityTabFullyLoaded","time":2290.5999999046326}],"measures":[],"correlationId":"951d1b82df5b47","effectiveType":"4g","downlink":10,"rtt":0,"serverDuration":147,"dbReadsTimeInMs":22,"dbConnsTimeInMs":32,"applicationHash":"9d11dbea5f4be3d4cc21f03a88dd11d8c8687422","experiments":[]}}
According to the numbers in the message, backup would need to copy 81,301,171,167 bytes (81 GB, 75.7 GiB) of log since the latest checkpoint at the time when the backup was started. It only managed to copy 28,263,961,570 bytes, a bit less than a third of that. Also that is a rather good achievement, because the circular ib_logfile0 that you configured must have been overwritten over 9 times (over 28 times if you used innodb_log_file_size=1g) while the backup is in progress.
If you had configured a large enough log file size, then this failure should occur only when the log is corrupted, possibly due to a file system error. MDEV-35791 might be such a case.
Given that the amount of log that needs to be copied is much larger than the configured log file size, I do not think that an attempt to force more frequent checkpoints (as discussed in
MDEV-30000) would help. What would definitely help would be to have some form of server-assisted log copying (MDEV-14992) or log archiving. In that way, the server would automatically throttle is write activity to ensure that the log for the backup is not missing anything.