MySQL 5.6主從報錯的實戰記錄

Posted on by

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主從報錯內容請搜索WalkonNet以前的文章或繼續瀏覽下面的相關文章希望大傢以後多多支持WalkonNet！

推薦閱讀：