MySQL 5.6 主从报错一例

老叶茶馆

发布于 2021-03-30 15:12:24

39900

代码可运行

文章被收录于专栏：MySQL修行 | 老叶茶馆MySQL修行 | 老叶茶馆

运行总次数：0

代码可运行

作者：八怪（高鹏）中亦科技数据库专家

1. 问题现象

版本：MySQL 5.6，采用传统 binlog file & pos 方式配置的主从复制结构。

实例重启后，主从复制报错如上图所示。

2. 错误含义

错误分为2部分。

第一部分

Client requested master to start replication from position > file size；
the first event 'mysql-bin.000398' at 163800795,the last event read from './mysql-binlog.000398' at 4,the last byte read from './mysql-bin.000398' at 4'

第一部分

这部分来源于主库的DUMP线程函数

mysql_binlog_send
  ->sender.run()
    ->Binlog_sender::init
       ->Binlog_sender::check_start_file

  if ((file= open_binlog_file(&cache, m_linfo.log_file_name, &errmsg)) < 0) 
  {
    set_fatal_error(errmsg);
    return 1;
  }

  size= my_b_filelength(&cache);
  end_io_cache(&cache);
  mysql_file_close(file, MYF(MY_WME)); 

  if (m_start_pos > size)
  {
    set_fatal_error("Client requested master to start replication from "
                    "position > file size");
    return 1;
  }

关键就是m_start_pos和size两个值，其中m_start_pos来源于从库需要读取的位点。而size则是本binlog文件的大小，那么很容易理解如果io线程需要的pos点比本binlog文件的大小还要大，那么自然不对。

第二部分

这部分也来源于DUMP线程

mysql_binlog_send
  ->sender.run()
     ->Binlog_sender::init
     ->while (!has_error() && !m_thd->killed)
     #如果正常这里开始循环读取binlog event，如果前面出错则直接继续后面逻辑
     #如果有读取错误则报错
       my_snprintf(error_text, sizeof(error_text),
                  "%s; the first event '%s' at %lld, "
                  "the last event read from '%s' at %lld, "
                  "the last byte read from '%s' at %lld.",
                  m_errmsg,
                  m_start_file, m_start_pos, m_last_file, m_last_pos,
                  log_file, my_b_tell(&log_cache));

这里我们主要看看m_start_pos和m_last_pos，实际上m_start_pos就是和前面报错一致的来自从库需要读取的位点信息，而m_last_pos来自dump线程，就是最后读取的位置，显然这里一次都没有读取，因此位置为最开始的pos 4。