Noticed this issue while writing the test case for MDEV-34156. Problem is that InnoDB
tries to write FILE_CHECKPOINT marker even though Innodb log file size is
insufficient. This could corrupt the log file and make it unrecoverable.
/* Write a FILE_CHECKPOINT marker as the first thing,
before generating any other redo log. This ensures
that subsequent crash recovery will be possible even
Wrote a test case to repeat the error and tried to avoid writing redo log for it.
But log_checkpoint_margin() eventually calls log_checkpoint and it triggers
fil_names_clear() and this could still corrupt the log file again.
Thread 2 hit Breakpoint 1, log_overwrite_warning (lsn=9826465) at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/mtr/mtr0mtr.cc:883
883 if (log_sys.overwrite_warned)
(rr) where
#0 log_overwrite_warning (lsn=9826465) at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/mtr/mtr0mtr.cc:883
#1 0x000055f5acd4852c in log_close (lsn=9826465) at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/mtr/mtr0mtr.cc:1086
#2 0x000055f5acd5138b in mtr_t::finish_writer<false, false> (mtr=0x7faefbffd6e0, len=118)
at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/mtr/mtr0mtr.cc:1393
#3 0x000055f5acd4cab9 in mtr_t::finish_write (this=0x7faefbffd6e0, len=118)
at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/include/mtr0mtr.h:724
#4 0x000055f5acd4795f in mtr_t::commit_files (this=0x7faefbffd6e0, checkpoint_lsn=63744)
at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/mtr/mtr0mtr.cc:785
#5 0x000055f5acfcd4d8 in fil_names_clear (lsn=63744) at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/fil/fil0fil.cc:3141
#6 0x000055f5acf578d5 in log_checkpoint_low (oldest_lsn=63744, end_lsn=9826347)
at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/buf/buf0flu.cc:1950
#7 0x000055f5acf588e4 in buf_flush_sync_for_checkpoint (lsn=1112053)
at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/buf/buf0flu.cc:2195
#8 0x000055f5acf59b8a in buf_flush_page_cleaner () at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/buf/buf0flu.cc:2439
#9 0x000055f5acf5d8c4 in std::__invoke_impl<void, void (*)()> (__f=@0x55f5af294e28: 0x55f5acf59913 <buf_flush_page_cleaner()>)
at /usr/include/c++/8/bits/invoke.h:60
#10 0x000055f5acf5d5f4 in std::__invoke<void (*)()> (__fn=@0x55f5af294e28: 0x55f5acf59913 <buf_flush_page_cleaner()>)
at /usr/include/c++/8/bits/invoke.h:95
#11 0x000055f5acf5dbe4 in std::thread::_Invoker<std::tuple<void (*)()> >::_M_invoke<0ul> (this=0x55f5af294e28)
at /usr/include/c++/8/thread:244
#12 0x000055f5acf5dbb6 in std::thread::_Invoker<std::tuple<void (*)()> >::operator() (this=0x55f5af294e28) at /usr/include/c++/8/thread:253
#13 0x000055f5acf5db96 in std::thread::_State_impl<std::thread::_Invoker<std::tuple<void (*)()> > >::_M_run (this=0x55f5af294e20)
at /usr/include/c++/8/thread:196
#14 0x00007faf1e6e62b3 in ?? () from /lib/x86_64-linux-gnu/libstdc++.so.6
#15 0x00007faf1e294ac3 in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:442
#16 0x00007faf1e325bf4 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:100
at /home/thiru/mariadb_parent/server/10.11-work/mysys/thr_mutex.c:489
#6 0x000055f5acf57d1a in buf_flush_wait (lsn=1112053) at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/buf/buf0flu.cc:2022
#7 0x000055f5acf581e2 in buf_flush_wait_flushed (sync_lsn=1112053)
at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/buf/buf0flu.cc:2080
#8 0x000055f5acd0718f in log_checkpoint_margin () at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/log/log0log.cc:1055
#9 0x000055f5acd072bf in log_free_check () at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/log/log0log.cc:1070
#10 0x000055f5acd9f9e5 in que_run_threads_low (thr=0x55f5af431f28)
at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/que/que0que.cc:639
#11 0x000055f5acd9fb09 in que_run_threads (thr=0x55f5af431f28)
at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/que/que0que.cc:664
#12 0x000055f5acd9fce6 in que_eval_sql (info=0x55f5af44c2b8,
sql=0x55f5ad7841a0 <row_merge_drop_temp_indexes()::sql> "PROCEDURE DROP_TEMP_INDEXES_PROC () IS\nixid CHAR;\nfound INT;\nDECLARE FUNCTION drop_fts;\nDECLARE CURSOR fts_cur IS\n SELECT TABLE_ID,ID FROM SYS_INDEXES\n WHERE TYPE=32 AND SUBSTR(NAME,0,1)='\377'\n FOR UPDA"...,
trx=0x7faf14720b80) at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/que/que0que.cc:703
#13 0x000055f5acdffd01 in row_merge_drop_temp_indexes ()
at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/row/row0merge.cc:4330
#14 0x000055f5ace80bf7 in srv_start (create_new_db=false)
at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/srv/srv0start.cc:1795
#15 0x000055f5acc276fa in innodb_init (p=0x55f5af17ccd8)
at /home/thiru/mariadb_parent/server/10.11-work/storage/innobase/handler/ha_innodb.cc:4230
#16 0x000055f5ac7e8b3f in ha_initialize_handlerton (plugin=0x55f5af0bdfe0)
at /home/thiru/mariadb_parent/server/10.11-work/sql/handler.cc:655
#17 0x000055f5ac440060 in plugin_do_initialize (plugin=0x55f5af0bdfe0, state=@0x7ffea2adb3f8: 4)
at /home/thiru/mariadb_parent/server/10.11-work/sql/sql_plugin.cc:1454
#18 0x000055f5ac440401 in plugin_initialize (tmp_root=0x7ffea2adb9b0, plugin=0x55f5af0bdfe0, argc=0x55f5ae16b618 <remaining_argc>,
argv=0x55f5af00aca8, options_only=false) at /home/thiru/mariadb_parent/server/10.11-work/sql/sql_plugin.cc:1507
create table t1(f1 int not null, f2 text)engine=innodb;
create table t2(f1 int not null, f2 text)engine=innodb;
let $shutdown_timeout=;
set global innodb_fast_shutdown=0;
let $restart_parameters=--debug_dbug=+d,ib_log_checkpoint_avoid_hard --innodb_flush_sync=0;
--source include/restart_mysqld.inc
insert into t1 select seq, repeat('b', 8000) from seq_1_to_128;
insert into t2 select seq, repeat('a', 8000) from seq_1_to_6;
delete from t2;
set global innodb_max_purge_lag_wait=0;
insert into t1 select seq, repeat('b', 8000) from seq_1_to_1024;
insert into t2 select seq, repeat('a', 8000) from seq_1_to_32;
let $restart_parameters=;
let $shutdown_timeout=0;
--source include/restart_mysqld.inc
alter table t1 force;
alter table t2 force;
drop table t1, t2;
This gives error during recovery:
2024-06-05 13:46:15 0 [ERROR] InnoDB: Crash recovery is broken due to insufficient innodb_log_file_size; last checkpoint LSN=63493, current LSN=9826481
Thirunarayanan Balathandayuthapani
added a comment - Test case to repeat the issue:
--source include/have_innodb.inc
--source include/have_sequence.inc
--source include/have_debug.inc
--source include/not_embedded.inc
create table t1(f1 int not null, f2 text)engine=innodb;
create table t2(f1 int not null, f2 text)engine=innodb;
let $shutdown_timeout=;
set global innodb_fast_shutdown=0;
let $restart_parameters=--debug_dbug=+d,ib_log_checkpoint_avoid_hard --innodb_flush_sync=0;
--source include/restart_mysqld.inc
insert into t1 select seq, repeat('b', 8000) from seq_1_to_128;
insert into t2 select seq, repeat('a', 8000) from seq_1_to_6;
delete from t2;
set global innodb_max_purge_lag_wait=0;
insert into t1 select seq, repeat('b', 8000) from seq_1_to_1024;
insert into t2 select seq, repeat('a', 8000) from seq_1_to_32;
let $restart_parameters=;
let $shutdown_timeout=0;
--source include/restart_mysqld.inc
alter table t1 force;
alter table t2 force;
drop table t1, t2;
This gives error during recovery:
2024-06-05 13:46:15 0 [ERROR] InnoDB: Crash recovery is broken due to insufficient innodb_log_file_size; last checkpoint LSN=63493, current LSN=9826481
The bug title needs to emphasize "during early recovery" because it is not possible or feasible to avoid writing a checkpoint marker later on in log_checkpoint_low(). The point of this fix is that if the server is killed during recovery, we are narrowing down the window of opportunity when the log will not be recoverable. During the log checkpoint at the end of recovery completed, we must already have written out all pending changes to the persistent files. What remains to be done is to write some log records for the checkpoint and to update the checkpoint header. Only if the server were killed between the two writes to the ib_logfile0, the log would be unrecoverable.
Marko Mäkelä
added a comment - The bug title needs to emphasize "during early recovery" because it is not possible or feasible to avoid writing a checkpoint marker later on in log_checkpoint_low(). The point of this fix is that if the server is killed during recovery, we are narrowing down the window of opportunity when the log will not be recoverable. During the log checkpoint at the end of recovery completed, we must already have written out all pending changes to the persistent files. What remains to be done is to write some log records for the checkpoint and to update the checkpoint header. Only if the server were killed between the two writes to the ib_logfile0 , the log would be unrecoverable.
People
Thirunarayanan Balathandayuthapani
Thirunarayanan Balathandayuthapani
Votes:
0Vote for this issue
Watchers:
2Start watching this issue
Dates
Created:
Updated:
Resolved:
Git Integration
Error rendering 'com.xiplink.jira.git.jira_git_plugin:git-issue-webpanel'. Please contact your Jira administrators.
{"report":{"fcp":1424.4000000953674,"ttfb":639.9000000953674,"pageVisibility":"visible","entityId":129579,"key":"jira.project.issue.view-issue","isInitial":true,"threshold":1000,"elementTimings":{},"userDeviceMemory":8,"userDeviceProcessors":64,"apdex":0.5,"journeyId":"ccae336a-fa93-4e0c-854e-d38ceae7b1b5","navigationType":0,"readyForUser":1517.0999999046326,"redirectCount":0,"resourceLoadedEnd":1551.6999998092651,"resourceLoadedStart":645.9000000953674,"resourceTiming":[{"duration":255.7999997138977,"initiatorType":"link","name":"https://jira.mariadb.org/s/2c21342762a6a02add1c328bed317ffd-CDN/lu2cib/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/css/_super/batch.css","startTime":645.9000000953674,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":645.9000000953674,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":901.6999998092651,"responseStart":0,"secureConnectionStart":0},{"duration":257.40000009536743,"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":646.1999998092651,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":646.1999998092651,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":903.5999999046326,"responseStart":0,"secureConnectionStart":0},{"duration":310.40000009536743,"initiatorType":"script","name":"https://jira.mariadb.org/s/0917945aaa57108d00c5076fea35e069-CDN/lu2cib/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/js/_super/batch.js?locale=en","startTime":646.2999997138977,"connectEnd":646.2999997138977,"connectStart":646.2999997138977,"domainLookupEnd":646.2999997138977,"domainLookupStart":646.2999997138977,"fetchStart":646.2999997138977,"redirectEnd":0,"redirectStart":0,"requestStart":646.2999997138977,"responseEnd":956.6999998092651,"responseStart":956.6999998092651,"secureConnectionStart":646.2999997138977},{"duration":369.19999980926514,"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":646.5,"connectEnd":646.5,"connectStart":646.5,"domainLookupEnd":646.5,"domainLookupStart":646.5,"fetchStart":646.5,"redirectEnd":0,"redirectStart":0,"requestStart":646.5,"responseEnd":1015.6999998092651,"responseStart":1015.6999998092651,"secureConnectionStart":646.5},{"duration":372.7000002861023,"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":646.6999998092651,"connectEnd":646.6999998092651,"connectStart":646.6999998092651,"domainLookupEnd":646.6999998092651,"domainLookupStart":646.6999998092651,"fetchStart":646.6999998092651,"redirectEnd":0,"redirectStart":0,"requestStart":646.6999998092651,"responseEnd":1019.4000000953674,"responseStart":1019.4000000953674,"secureConnectionStart":646.6999998092651},{"duration":373,"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":646.9000000953674,"connectEnd":646.9000000953674,"connectStart":646.9000000953674,"domainLookupEnd":646.9000000953674,"domainLookupStart":646.9000000953674,"fetchStart":646.9000000953674,"redirectEnd":0,"redirectStart":0,"requestStart":646.9000000953674,"responseEnd":1019.9000000953674,"responseStart":1019.9000000953674,"secureConnectionStart":646.9000000953674},{"duration":373.2999997138977,"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":647,"connectEnd":647,"connectStart":647,"domainLookupEnd":647,"domainLookupStart":647,"fetchStart":647,"redirectEnd":0,"redirectStart":0,"requestStart":647,"responseEnd":1020.2999997138977,"responseStart":1020.2999997138977,"secureConnectionStart":647},{"duration":457.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":647.1999998092651,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":647.1999998092651,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1104.6999998092651,"responseStart":0,"secureConnectionStart":0},{"duration":373.2999997138977,"initiatorType":"script","name":"https://jira.mariadb.org/rest/api/1.0/shortcuts/820016/47140b6e0a9bc2e4913da06536125810/shortcuts.js?context=issuenavigation&context=issueaction","startTime":647.4000000953674,"connectEnd":647.4000000953674,"connectStart":647.4000000953674,"domainLookupEnd":647.4000000953674,"domainLookupStart":647.4000000953674,"fetchStart":647.4000000953674,"redirectEnd":0,"redirectStart":0,"requestStart":647.4000000953674,"responseEnd":1020.6999998092651,"responseStart":1020.6999998092651,"secureConnectionStart":647.4000000953674},{"duration":457.30000019073486,"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":647.5999999046326,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":647.5999999046326,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1104.9000000953674,"responseStart":0,"secureConnectionStart":0},{"duration":373.5,"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":647.7999997138977,"connectEnd":647.7999997138977,"connectStart":647.7999997138977,"domainLookupEnd":647.7999997138977,"domainLookupStart":647.7999997138977,"fetchStart":647.7999997138977,"redirectEnd":0,"redirectStart":0,"requestStart":647.7999997138977,"responseEnd":1021.2999997138977,"responseStart":1021.2999997138977,"secureConnectionStart":647.7999997138977},{"duration":695.8000001907349,"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":648.5999999046326,"connectEnd":648.5999999046326,"connectStart":648.5999999046326,"domainLookupEnd":648.5999999046326,"domainLookupStart":648.5999999046326,"fetchStart":648.5999999046326,"redirectEnd":0,"redirectStart":0,"requestStart":648.5999999046326,"responseEnd":1344.4000000953674,"responseStart":1344.4000000953674,"secureConnectionStart":648.5999999046326},{"duration":861.7000002861023,"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":648.6999998092651,"connectEnd":648.6999998092651,"connectStart":648.6999998092651,"domainLookupEnd":648.6999998092651,"domainLookupStart":648.6999998092651,"fetchStart":648.6999998092651,"redirectEnd":0,"redirectStart":0,"requestStart":648.6999998092651,"responseEnd":1510.4000000953674,"responseStart":1510.4000000953674,"secureConnectionStart":648.6999998092651},{"duration":243.09999990463257,"initiatorType":"xmlhttprequest","name":"https://jira.mariadb.org/rest/webResources/1.0/resources","startTime":1117.0999999046326,"connectEnd":1117.0999999046326,"connectStart":1117.0999999046326,"domainLookupEnd":1117.0999999046326,"domainLookupStart":1117.0999999046326,"fetchStart":1117.0999999046326,"redirectEnd":0,"redirectStart":0,"requestStart":1117.0999999046326,"responseEnd":1360.1999998092651,"responseStart":1360.1999998092651,"secureConnectionStart":1117.0999999046326},{"duration":174.19999980926514,"initiatorType":"link","name":"https://jira.mariadb.org/s/d5715adaadd168a9002b108b2b039b50-CDN/lu2cib/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&whisper-enabled=true","startTime":1377.5,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":1377.5,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1551.6999998092651,"responseStart":0,"secureConnectionStart":0},{"duration":147.5,"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":1378.5,"connectEnd":1378.5,"connectStart":1378.5,"domainLookupEnd":1378.5,"domainLookupStart":1378.5,"fetchStart":1378.5,"redirectEnd":0,"redirectStart":0,"requestStart":1378.5,"responseEnd":1526,"responseStart":1526,"secureConnectionStart":1378.5},{"duration":153.69999980926514,"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":1378.9000000953674,"connectEnd":1378.9000000953674,"connectStart":1378.9000000953674,"domainLookupEnd":1378.9000000953674,"domainLookupStart":1378.9000000953674,"fetchStart":1378.9000000953674,"redirectEnd":0,"redirectStart":0,"requestStart":1378.9000000953674,"responseEnd":1532.5999999046326,"responseStart":1532.5999999046326,"secureConnectionStart":1378.9000000953674},{"duration":199.2000002861023,"initiatorType":"script","name":"https://www.google-analytics.com/analytics.js","startTime":1418.2999997138977,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":1418.2999997138977,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1617.5,"responseStart":0,"secureConnectionStart":0}],"fetchStart":1,"domainLookupStart":1,"domainLookupEnd":1,"connectStart":1,"connectEnd":1,"requestStart":465,"responseStart":640,"responseEnd":642,"domLoading":644,"domInteractive":1639,"domContentLoadedEventStart":1639,"domContentLoadedEventEnd":1690,"domComplete":1782,"loadEventStart":1783,"loadEventEnd":1783,"userAgent":"Mozilla/5.0 AppleWebKit/537.36 (KHTML, like Gecko; compatible; ClaudeBot/1.0; +claudebot@anthropic.com)","marks":[{"name":"bigPipe.sidebar-id.start","time":1618.1999998092651},{"name":"bigPipe.sidebar-id.end","time":1619},{"name":"bigPipe.activity-panel-pipe-id.start","time":1619.0999999046326},{"name":"bigPipe.activity-panel-pipe-id.end","time":1621},{"name":"activityTabFullyLoaded","time":1717.0999999046326}],"measures":[],"correlationId":"c920c0d0aedf25","effectiveType":"4g","downlink":9.3,"rtt":0,"serverDuration":104,"dbReadsTimeInMs":13,"dbConnsTimeInMs":22,"applicationHash":"9d11dbea5f4be3d4cc21f03a88dd11d8c8687422","experiments":[]}}
Test case to repeat the issue:
--source include/have_innodb.inc
--source include/have_sequence.inc
--source include/have_debug.inc
--source include/not_embedded.inc
create table t1(f1 int not null, f2 text)engine=innodb;
create table t2(f1 int not null, f2 text)engine=innodb;
let $shutdown_timeout=;
set global innodb_fast_shutdown=0;
let $restart_parameters=--debug_dbug=+d,ib_log_checkpoint_avoid_hard --innodb_flush_sync=0;
--source include/restart_mysqld.inc
insert into t1 select seq, repeat('b', 8000) from seq_1_to_128;
insert into t2 select seq, repeat('a', 8000) from seq_1_to_6;
delete from t2;
set global innodb_max_purge_lag_wait=0;
insert into t1 select seq, repeat('b', 8000) from seq_1_to_1024;
insert into t2 select seq, repeat('a', 8000) from seq_1_to_32;
let $restart_parameters=;
let $shutdown_timeout=0;
--source include/restart_mysqld.inc
alter table t1 force;
alter table t2 force;
drop table t1, t2;
This gives error during recovery:
2024-06-05 13:46:15 0 [ERROR] InnoDB: Crash recovery is broken due to insufficient innodb_log_file_size; last checkpoint LSN=63493, current LSN=9826481