MySQL 5.6 主从报错一例

ゝ一纸荒年。 2022-11-09 11:27 101阅读 0赞

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

## 1. 问题现象 ##

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

###  ###

![caa33305149d292831133616d383880b.png][]

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

### 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。

### 3. 可能的原因 ###

分析后觉得最有可能原因应该和sync\_binlog 有关。

如果我们没有设置为1，那么可能os cache没有刷盘，如果主库服务器直接crash重启很容易就遇到这种问题。

稍微google查询了一下发现很大部分出现这种错误都是由于服务器crash且sync\_binlog 没设置为 1导致的。

这也证明我们的说法。

最后查看问题数据库的主库确实没有设置为双1。

那么通过这个小案例，我们已经更加深刻体会到设置双1的重要性。

全文完。

Enjoy MySQL :)

扫码添加作者微信

![a97fe2e3d076846cad3846c295532ecc.png][]

[caa33305149d292831133616d383880b.png]: /images/20221023/5730197f1a0a41ddb0bab9959740a491.png
[a97fe2e3d076846cad3846c295532ecc.png]: /images/20221023/e670d986229a43b693ded3a06b65d82c.png