如何淺析MySQL中的binlog和redo

如何淺析MySQL中的binlog和redo，很多新手對此不是很清楚，為了幫助大家解決這個難題，下面小編將為大家詳細講解，有這方面需求的人可以來學習下，希望你能有所收獲。

   有一個小問題可能很多人都想起過，那就是MySQL中既然已經有了binlog,為什么還需要redo,這個問題看起來好像很簡單，但是細細品來，還是有不少值得注意的地方。

    對于數據恢復，尤其是異常宕機的情況下，再次啟動的時候，如何恢復，恢復的數據依據，這個尤為重要，在MySQL中是有checkpoint的技術來做一個基本的檢查點控制，也就是常說的LSN,對于事務性數據庫，大都會采用write ahead log的策略，即當前事務提交的時候，先寫redo,在修改相應的頁，如果發生宕機導致數據丟失的時候，可以通過重做日志來完成數據的恢復，但是MySQL和其它有些數據庫有些特別的是這個binlog,它不是采用checkpoint的實現方式，我們可以設想這樣一個情況，一個事務提交的時候，信息會寫入redo,而在這個操作的過程中，其實binlog的寫入也是同步的，如果redo的信息在redo log buffer中可能還沒有刷新到磁盤中，出現宕機的情況，就可能導致從庫的數據已經應用了binlog傳輸的數據變化，而redo中還沒來得及提交，這可能就會有數據不一致的情況發生，如果在異常狀態下啟動數據庫就會開啟數據恢復的模式，可能從庫的數據就會出現不一致。

   這種情況聽起來有些特別，但是對于我們理解redo和binlog的問題蠻有幫助，我們來做一個測試吧，僅僅在測試環境中進行調試所用。

首先為了減少數據的變更影響，我們先做一個flush logs的操作，盡可能保留少，數據變化新的日志內容

在主庫端切換日志:
flush logs;

查看binlog的情況，使用show master status或者show binary logs都可以。
mysql> show master status\G
*************************** 1. row ***************************
             File: binlog.000014
         Position: 230
     Binlog_Do_DB:
 Binlog_Ignore_DB:
Executed_Gtid_Set: 1bb1b861-f776-11e6-be42-782bcb377193:1,
25ee7482-07cd-11e7-a40c-0026b935eb76:1-1502468
1 row in set (0.00 sec)

我們得到mysql服務的進程號。
# ps -ef|grep -w mysqld|grep -v grep|awk '{print $2}'
1751

我們創建一個表test 字段為id和name(id int ,name varchar(20))

已經存在4條數據如下：

mysql> select *from test.test;
+------+------+
| id   | name |
+------+------+
|    1 | aa   |
|    2 | bb   |
|    3 | cc   |
|    4 | dd   |
+------+------+
4 rows in set (0.00 sec)

從庫 查看數據和主庫此時是同步的。這是我們測試的一個基礎。

我們可以通過gdb的方式進行簡單調試。
# gdb -p 1751

就馬上進入了調試模式，我們可以設置一個斷點。

我們在設置斷點之前先插入2條數據，從庫此時也是5條數據。

mysql> insert into test values(5,'ee');
Query OK, 1 row affected (0.00 sec)

mysql> insert into test values(6,'ff');
Query OK, 1 row affected (0.00 sec)

然后設置斷點，這是關鍵所在。
(gdb) b MYSQL_BIN_LOG::process_commit_stage_queue
Breakpoint 1 at 0xec73ca: file /export/home/pb2/build/sb_0-21378219-1480347226.17/mysql-5.7.17/sql/binlog.cc, line 8430. (2 locations)

然后在主庫嘗試插入一條記錄
insert into test values(7,'gg');
毫無疑問，這條語句會hang住。因為我們的斷點就在提交的時候。

這個時候我們前進一小步，使用c即continue

(gdb) c
Continuing.
[Switching to Thread 0x409c0940 (LWP 1798)]

Breakpoint 1, MYSQL_BIN_LOG::process_commit_stage_queue (this=0x1e8ba00, thd=0xec254e0, first=0xec254e0)
    at /export/home/pb2/build/sb_0-21378219-1480347226.17/mysql-5.7.17/sql/binlog.cc:8430
8430    /export/home/pb2/build/sb_0-21378219-1480347226.17/mysql-5.7.17/sql/binlog.cc: No such file or directory.
        in /export/home/pb2/build/sb_0-21378219-1480347226.17/mysql-5.7.17/sql/binlog.cc
這個時候那條SQL語句依舊是hang的狀態，但是可以看出堆棧，binlog是寫入完成了

從庫此時是應用了數據變更，此時是7條數據。
我們也可以抓取一下binlog,看看里面是否已經寫入了數據。
[root@grtest s1]# /usr/local/mysql/bin/mysqlbinlog -vv binlog.0000014
可以明顯看到這樣的語句：

...

BINLOG '
DK3KWBPqDAAALgAAAHcDAAAAAOUAAAAAAAEABHRlc3QABHRlc3QAAgMPAhQAAw==
DK3KWB7qDAAAJwAAAJ4DAAAAAOUAAAAAAAEAAgAC//wHAAAAAmdn
'/*!*/;
### INSERT INTO `test`.`test`
### SET
###   @1=7 /* INT meta=0 nullable=1 is_null=0 */
###   @2='gg' /* VARSTRING(20) meta=20 nullable=1 is_null=0 */
# at 926
#170316 23:19:40 server id 3306  end_log_pos 953        Xid = 55
COMMIT/*!*/;
我們此時模擬宕機的情況，殺掉進程

 kill -9 1751 29617

然后把binlog改個名字，關閉log_bin
[root@grtest s1]# mv binlog.000014  binlog.000014.bak
再次啟動之后，就會發現此時的主庫中數據還是6條，而從庫卻是7條。
  而如果我們把binlog改回來，開啟log_bin并啟動主庫
mv binlog.000014.bak binlog.000014

   然后再次查看數據，就會發現主從庫此時的數據竟然不同。從庫的數據明顯要多，這也就從一個側面映射了我們開始的一個設想，在異常宕機的情況下，redo的數據還沒有刷新到redo文件中，此時已經寫入了binlog,這樣就在這樣一個臨界點導致了主從數據的不一致。

   當然我是使用一個調試的態度來做的測試，里面還有很多技巧需要鞏固。

看完上述內容是否對您有幫助呢？如果還想對相關知識有進一步的了解或閱讀更多相關文章，請關注億速云行業資訊頻道，感謝您對億速云的支持。