[MDEV-15662]  mariabackup.huge_lsn fails sporadically with "log sequence number is in the future" Created: 2018-03-26  Updated: 2020-07-14  Resolved: 2020-07-14

Status: Closed
Project: MariaDB Server
Component/s: Backup, Tests
Affects Version/s: 10.1, 10.3.5, 10.2, 10.4, 10.5
Fix Version/s: 10.1.46, 10.2.33, 10.3.24, 10.4.14, 10.5.5

Type: Bug Priority: Major
Reporter: Zdravelina Sokolovska (Inactive) Assignee: Thirunarayanan Balathandayuthapani
Resolution: Fixed Votes: 0
Labels: None
Environment:

CentOS 7.4

Attachments: File mdev-15562.patch    

 Description   

maridb mtr mariabackup.huge_lsn test fails with [ERROR] InnoDB: Page [page id: space=0, page number=7] log sequence number 17596481014082 is in the future! Current system log sequence number 17596481010709.

The result from queries just before the failure was:
MDEV-13416 mariabackup fails with EFAULT "Bad Address"

The result from queries just before the failure was:
 
#
 
# MDEV-13416 mariabackup fails with EFAULT "Bad Address"
 
#
 
FOUND 1 /InnoDB: New log files created, LSN=175964\d{8}/ in mysqld.1.err
 
CREATE TABLE t(i INT) ENGINE INNODB;
 
INSERT INTO t VALUES(1);
 
# xtrabackup backup
 
SET GLOBAL innodb_flush_log_at_trx_commit=1;
 
INSERT INTO t VALUES(2);
 
# xtrabackup prepare
 
# shutdown server
 
# remove datadir
 
# xtrabackup move back
 
# restart server
 
SELECT * FROM t;
 
 
 
 - saving '/usr/share/mysql-test/var/log/mariabackup.huge_lsn/' to '/usr/share/mysql-test/var/log/mariabackup.huge_lsn/'
 
***Warnings generated in error logs during shutdown after running tests: mariabackup.huge_lsn
 
 
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Page [page id: space=0, page number=7] log sequence number 17596481014082 is in the future! Current system log sequence number 17596481010709.
 



mariabackup.huge_lsn                     [ pass ]   7715
 
mariabackup.huge_lsn                     [ fail ]
 
        Test ended at 2018-03-22 14:13:30
 
 
 
CURRENT_TEST: mariabackup.huge_lsn
 
/usr/bin/mariabackup based on MariaDB server 10.3.5-MariaDB Linux (x86_64)
 
180322 14:13:29 [01] Copying ibdata1 to /usr/share/mysql-test/var/mysqld.1/data/ibdata1
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./xtrabackup_binlog_pos_innodb to /usr/share/mysql-test/var/mysqld.1/data/xtrabackup_binlog_pos_innodb
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./xtrabackup_info to /usr/share/mysql-test/var/mysqld.1/data/xtrabackup_info
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./ib_buffer_pool to /usr/share/mysql-test/var/mysqld.1/data/ib_buffer_pool
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./aria_log_control to /usr/share/mysql-test/var/mysqld.1/data/aria_log_control
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./aria_log.00000001 to /usr/share/mysql-test/var/mysqld.1/data/aria_log.00000001
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mtr/global_suppressions.TRG to /usr/share/mysql-test/var/mysqld.1/data/mtr/global_suppressions.TRG
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mtr/gs_insert.TRN to /usr/share/mysql-test/var/mysqld.1/data/mtr/gs_insert.TRN
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mtr/global_suppressions.MYD to /usr/share/mysql-test/var/mysqld.1/data/mtr/global_suppressions.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mtr/global_suppressions.MYI to /usr/share/mysql-test/var/mysqld.1/data/mtr/global_suppressions.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mtr/global_suppressions.frm to /usr/share/mysql-test/var/mysqld.1/data/mtr/global_suppressions.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mtr/test_suppressions.TRG to /usr/share/mysql-test/var/mysqld.1/data/mtr/test_suppressions.TRG
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mtr/ts_insert.TRN to /usr/share/mysql-test/var/mysqld.1/data/mtr/ts_insert.TRN
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mtr/test_suppressions.MYD to /usr/share/mysql-test/var/mysqld.1/data/mtr/test_suppressions.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mtr/test_suppressions.MYI to /usr/share/mysql-test/var/mysqld.1/data/mtr/test_suppressions.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mtr/test_suppressions.frm to /usr/share/mysql-test/var/mysqld.1/data/mtr/test_suppressions.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mtr/db.opt to /usr/share/mysql-test/var/mysqld.1/data/mtr/db.opt
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./performance_schema/db.opt to /usr/share/mysql-test/var/mysqld.1/data/performance_schema/db.opt
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./test/t.frm to /usr/share/mysql-test/var/mysqld.1/data/test/t.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./test/t.ibd to /usr/share/mysql-test/var/mysqld.1/data/test/t.ibd
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/gtid_slave_pos.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/gtid_slave_pos.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/gtid_slave_pos.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/gtid_slave_pos.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/gtid_slave_pos.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/gtid_slave_pos.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/index_stats.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/index_stats.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/index_stats.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/index_stats.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/index_stats.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/index_stats.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/column_stats.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/column_stats.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/column_stats.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/column_stats.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/column_stats.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/column_stats.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/table_stats.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/table_stats.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/table_stats.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/table_stats.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/table_stats.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/table_stats.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/proxies_priv.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/proxies_priv.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/proxies_priv.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/proxies_priv.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/proxies_priv.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/proxies_priv.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/transaction_registry.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/transaction_registry.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/innodb_index_stats.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/innodb_index_stats.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/innodb_table_stats.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/innodb_table_stats.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/event.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/event.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/event.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/event.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/event.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/event.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/slow_log.CSV to /usr/share/mysql-test/var/mysqld.1/data/mysql/slow_log.CSV
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/slow_log.CSM to /usr/share/mysql-test/var/mysqld.1/data/mysql/slow_log.CSM
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/slow_log.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/slow_log.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/general_log.CSV to /usr/share/mysql-test/var/mysqld.1/data/mysql/general_log.CSV
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/general_log.CSM to /usr/share/mysql-test/var/mysqld.1/data/mysql/general_log.CSM
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/general_log.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/general_log.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/procs_priv.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/procs_priv.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/procs_priv.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/procs_priv.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/procs_priv.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/procs_priv.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/proc.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/proc.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/proc.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/proc.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/proc.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/proc.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/time_zone_leap_second.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/time_zone_leap_second.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/time_zone_leap_second.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/time_zone_leap_second.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/time_zone_leap_second.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/time_zone_leap_second.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/time_zone_transition_type.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/time_zone_transition_type.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/time_zone_transition_type.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/time_zone_transition_type.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/time_zone_transition_type.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/time_zone_transition_type.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/time_zone_transition.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/time_zone_transition.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/time_zone_transition.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/time_zone_transition.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/time_zone_transition.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/time_zone_transition.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/time_zone.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/time_zone.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/time_zone.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/time_zone.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/time_zone.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/time_zone.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/time_zone_name.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/time_zone_name.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/time_zone_name.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/time_zone_name.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/time_zone_name.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/time_zone_name.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/help_keyword.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/help_keyword.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/help_keyword.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/help_keyword.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/help_keyword.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/help_keyword.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/help_relation.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/help_relation.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/help_relation.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/help_relation.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/help_relation.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/help_relation.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/help_category.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/help_category.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/help_category.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/help_category.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/help_category.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/help_category.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/help_topic.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/help_topic.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/help_topic.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/help_topic.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/help_topic.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/help_topic.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/columns_priv.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/columns_priv.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/columns_priv.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/columns_priv.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/columns_priv.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/columns_priv.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/tables_priv.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/tables_priv.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/tables_priv.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/tables_priv.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/tables_priv.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/tables_priv.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/servers.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/servers.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/servers.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/servers.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/servers.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/servers.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/plugin.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/plugin.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/plugin.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/plugin.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/plugin.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/plugin.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/func.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/func.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/func.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/func.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/func.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/func.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/roles_mapping.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/roles_mapping.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/roles_mapping.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/roles_mapping.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/roles_mapping.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/roles_mapping.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/user.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/user.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/user.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/user.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/user.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/user.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/host.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/host.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/host.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/host.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/host.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/host.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/db.MYD to /usr/share/mysql-test/var/mysqld.1/data/mysql/db.MYD
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/db.MYI to /usr/share/mysql-test/var/mysqld.1/data/mysql/db.MYI
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/db.frm to /usr/share/mysql-test/var/mysqld.1/data/mysql/db.frm
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/transaction_registry.ibd to /usr/share/mysql-test/var/mysqld.1/data/mysql/transaction_registry.ibd
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/innodb_index_stats.ibd to /usr/share/mysql-test/var/mysqld.1/data/mysql/innodb_index_stats.ibd
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 [01] Copying ./mysql/innodb_table_stats.ibd to /usr/share/mysql-test/var/mysqld.1/data/mysql/innodb_table_stats.ibd
 
180322 14:13:29 [01]        ...done
 
180322 14:13:29 completed OK!
 
mysqltest: At line 50: query 'SELECT * FROM t' failed: 1932: Table 'test.t' doesn't exist in engine
 
 
 
The result from queries just before the failure was:
 
#
 
# MDEV-13416 mariabackup fails with EFAULT "Bad Address"
 
#
 
FOUND 1 /InnoDB: New log files created, LSN=175964\d{8}/ in mysqld.1.err
 
CREATE TABLE t(i INT) ENGINE INNODB;
 
INSERT INTO t VALUES(1);
 
# xtrabackup backup
 
SET GLOBAL innodb_flush_log_at_trx_commit=1;
 
INSERT INTO t VALUES(2);
 
# xtrabackup prepare
 
# shutdown server
 
# remove datadir
 
# xtrabackup move back
 
# restart server
 
SELECT * FROM t;
 
 
 
 - saving '/usr/share/mysql-test/var/log/mariabackup.huge_lsn/' to '/usr/share/mysql-test/var/log/mariabackup.huge_lsn/'
 
***Warnings generated in error logs during shutdown after running tests: mariabackup.huge_lsn
 
 
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Page [page id: space=0, page number=7] log sequence number 17596481014082 is in the future! Current system log sequence number 17596481010709.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Page [page id: space=0, page number=11] log sequence number 17596481015608 is in the future! Current system log sequence number 17596481010709.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Page [page id: space=0, page number=46] log sequence number 17596481015591 is in the future! Current system log sequence number 17596481010709.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Page [page id: space=0, page number=309] log sequence number 17596481015591 is in the future! Current system log sequence number 17596481010709.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Page [page id: space=0, page number=47] log sequence number 17596481014310 is in the future! Current system log sequence number 17596481010709.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Page [page id: space=0, page number=311] log sequence number 17596481014310 is in the future! Current system log sequence number 17596481010709.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Page [page id: space=0, page number=8] log sequence number 17596481015623 is in the future! Current system log sequence number 17596481010709.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Page [page id: space=0, page number=10] log sequence number 17596481015613 is in the future! Current system log sequence number 17596481010709.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Page [page id: space=0, page number=307] log sequence number 17596481015597 is in the future! Current system log sequence number 17596481010709.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Page [page id: space=0, page number=308] log sequence number 17596481015603 is in the future! Current system log sequence number 17596481010709.
 
2018-03-22 14:13:25 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:25 1 [ERROR] InnoDB: Page [page id: space=0, page number=9] log sequence number 17596481015618 is in the future! Current system log sequence number 17596481010709.
 
2018-03-22 14:13:25 1 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:30 0 [ERROR] InnoDB: Page [page id: space=0, page number=11] log sequence number 17596481015608 is in the future! Current system log sequence number 17596481014805.
 
2018-03-22 14:13:30 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:30 0 [ERROR] InnoDB: Page [page id: space=0, page number=46] log sequence number 17596481015591 is in the future! Current system log sequence number 17596481014805.
 
2018-03-22 14:13:30 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:30 0 [ERROR] InnoDB: Page [page id: space=0, page number=309] log sequence number 17596481015591 is in the future! Current system log sequence number 17596481014805.
 
2018-03-22 14:13:30 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:30 0 [ERROR] InnoDB: Page [page id: space=0, page number=8] log sequence number 17596481015623 is in the future! Current system log sequence number 17596481014805.
 
2018-03-22 14:13:30 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:30 0 [ERROR] InnoDB: Page [page id: space=0, page number=10] log sequence number 17596481015613 is in the future! Current system log sequence number 17596481014805.
 
2018-03-22 14:13:30 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:30 0 [ERROR] InnoDB: Page [page id: space=0, page number=307] log sequence number 17596481015597 is in the future! Current system log sequence number 17596481014805.
 
2018-03-22 14:13:30 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:30 0 [ERROR] InnoDB: Page [page id: space=0, page number=308] log sequence number 17596481015603 is in the future! Current system log sequence number 17596481014805.
 
2018-03-22 14:13:30 0 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
2018-03-22 14:13:30 2 [ERROR] InnoDB: Page [page id: space=0, page number=9] log sequence number 17596481015618 is in the future! Current system log sequence number 17596481014805.
 
2018-03-22 14:13:30 2 [ERROR] InnoDB: Your database may be corrupt or you may have copied the InnoDB tablespace but not the InnoDB log files. Please refer to https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/ for information about forcing recovery.
 
 
 
mariabackup.huge_lsn                     [ pass ]   7613



 Comments   
Comment by Marko Mäkelä [ 2018-04-07 ]

This should be a bug in the test, not in mariabackup. The test is fast-forwarding the LSN of the server, and it is possible that there is some fault in that logic. The test also fails with

./mtr --repeat=2 mariabackup.huge_lsn

Comment by Marko Mäkelä [ 2018-10-22 ]

Note: The MDEV-15562 (instant DROP COLUMN) was mistakenly pushed with this MDEV number.

Comment by Thirunarayanan Balathandayuthapani [ 2020-07-09 ]

mdev-15562.patch

The attached patch solves the issue by taking backup of earlier data. Problem is that test case recreates the iblogfile and the ibdata pages could point to
future LSN.

Comment by Marko Mäkelä [ 2020-07-13 ]

Looks OK. Thank you!

Generated at Thu Feb 08 08:23:05 UTC 2024 using Jira 8.20.16#820016-sha1:9d11dbea5f4be3d4cc21f03a88dd11d8c8687422.