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。

3. 可能的原因

分析后覺得最有可能原因應該和sync_binlog 有關。

如果我們沒有設置為1，那么可能os cache沒有刷盤，如果主庫服務器直接crash重啟很容易就遇到這種問題。

稍微google查詢了一下發現很大部分出現這種錯誤都是由于服務器crash且sync_binlog 沒設置為 1導致的。

這也證明我們的說法。

最后查看問題數據庫的主庫確實沒有設置為雙1。

那么通過這個小案例，我們已經更加深刻體會到設置雙1的重要性。

總結

到此這篇關于MySQL 5.6主從報錯的文章就介紹到這了,更多相關MySQL5.6主從報錯內容請搜索腳本之家以前的文章或繼續瀏覽下面的相關文章希望大家以后多多支持腳本之家！