如何读懂SQL Server的事务日志

9/1/2015来源:SQL技巧人气:3071

如何读懂SQL Server的事务日志简介

本文将介绍SQL Server的事务日志中记录了哪一些信息,如何来读懂这些事务日志中信息。首先介绍一个微软没有公开的函数fn_dblog,在文章的接下来的部分主要用到这个函数来读取事务日志。

  1. fn_dblog(@StartingLSN,@EndingLSN)
  2. [email protected]:表示起始的LSN号,如果为NULL值则表示从首日志记录开始查询。
  3. [email protected]:表示结束的LSN号,如果为NULL值则表示查询到尾日志记录。
  4. --需要注意的是我们平时所看到的LSN都是十六进制的,而这边的参数需要转化为十进制,如00000021:00000077:0003在作为参数传给fn_dblog时需要转换为33:119:3
正文
  1. --创建测试数据库
  2. USE [master];
  3. GO
  4. CREATEDATABASE TestDB;
  5. GO
  6. -- 创建表
  7. USE TestDB;
  8. GO
  9. CREATETABLE [Location] (
  10.     [Sr.No] INTIDENTITY,
  11.     [Date] DATETIMEDEFAULT GETDATE (),
  12.     [City] CHAR (25) DEFAULT 'xiamen');

通过上面的代码创建了一个名为TestDB的数据库,并创建了一个三个字段的表。接下看看事务日志的内容

  1. USE TestDB;
  2. GO
  3. select [Current LSN],
  4.        [Operation],
  5.        [Transaction Name],
  6.        [Transaction ID],
  7.        [Transaction SID],
  8.        [SPID],
  9.        [BeginTime]
  10. FROMfn_dblog(null,null)

从上图可以看出总共产生了195行日志记录,我截取了部分的结果,在Operation列中记录了对应的LSN所做的操作,其中LOP_BEGIN_XACT表示一个事务的开始,Transaction Name显示了创建的数据库的名称,而Trasaction ID则记录了所对应的事务ID。下面列出Operation几种比较常见而重要的值

  • LOP_BEGIN_XACT 事务的开始
  • LOP_LOCK_XACT 获取锁
  • LOP_MODIFY_ROW 修改行(具体修改的对象可以查看AllocUnitName)
  • LOP_COMMIT_XACT 提交事务
  • LOP_DELETE_ROWS 删除数据
  • LOP_INSERT_ROWS 插入数据

接下来向表中插入100行数据,并查看对应的事务日志,代码如下:

  1. INSERTINTO Location DEFAULTVALUES
  2. GO 100
  3. GO
  4.  
  5. SELECT
  6.  [Current LSN],
  7.  [Transaction ID],
  8.  [Operation],
  9.   [Transaction Name],
  10.  [CONTEXT],
  11.  [AllocUnitName],
  12.  [Page ID],
  13.  [Slot ID],
  14.  [BeginTime],
  15.  [EndTime],
  16.  [Number of Locks],
  17.  [Lock Information]
  18. FROM sys.fn_dblog(NULL,NULL)
  19. WHERE Operation = 'LOP_INSERT_ROWS' AND AllocUnitName = 'dbo.Location'

得到如上图所示的结果,返回的行数与我们insert的次数一致,接下来取其中的一个Trasaction ID来看看一次insert在事务日志中记录了哪些动作。

  1. SELECT
  2.  [Current LSN], [Transaction ID], [Operation], [Transaction Name], [CONTEXT], [AllocUnitName], [Page ID], [Slot ID], [BeginTime], [EndTime], [Number of Locks], [Lock Information]
  3. FROM sys.fn_dblog(NULL,NULL)
  4. WHERE [Transaction ID] = '0000:000002fc'

从图中可以看出这个Transaction执行步骤的详细信息

  • 在2014/05/25 18:35:39:197事务开始
  • 在堆表dbo.Location的PAGEID为0001:0000004f插入数据
  • 在2014/05/24 18:35:39:200提交事务

下面这一段是我从Lock Information栏位复制出的内容,来详细的看一下

  1. HoBt 72057594039042048:ACQUIRE_LOCK_IX OBJECT: 6:245575913:0 ;ACQUIRE_LOCK_IX PAGE: 6:1:79 ;ACQUIRE_LOCK_X RID: 6:1:79:0

通过下面的代码我们来验证一下,这样一条INSERT语句所获得的锁信息

  1. DBCC TRACEON(-1,3604)
  2. DBCC TRACEON (-1,1200)--查看当前session的锁信息
  3. BEGINTRAN
  4. INSERTINTO Location DEFAULTVALUES
  5. ROLLBACKTRAN
  6. DBCC TRACEOFF ( -1,1200)
  7. DBCC TRACEOFF ( -1,3604)
  8. /*
  9. PRocess 57 acquiring IX lock on OBJECT: 6:245575913:0 (class bit2000000 ref1) result: OK
  10. Process 57 acquiring IX lock on PAGE: 6:1:79 (class bit2000000 ref0) result: OK
  11. Process 57 acquiring X lock on RID: 6:1:79:90 (class bit2000000 ref0) result: OK
  12. */

可以看到Lock Information栏位所记录的信息与1200跟踪标记锁输出的信息是一致的。

另外从事务日志中还可以看到SQL Server的一些内部操作,并看到这些操作一些具体信息,如开始的时间,进行的次数,操作的步骤等等。接下来看看页拆分的动作

  1. --查找页拆分动作的Transaction
  2. SELECT
  3.  [Current LSN], [Transaction ID], [Operation], [Transaction Name], [CONTEXT], [AllocUnitName], [Page ID], [Slot ID], [BeginTime], [EndTime], [Number of Locks], [Lock Information]
  4. FROM sys.fn_dblog(NULL,NULL)
  5. WHERE [Transaction Name] = 'SplitPage'
  6. --查看具体Transaction中的动作
  7. SELECT
  8.  [Current LSN], [Transaction ID], [Operation], [Transaction Name], [CONTEXT], [AllocUnitName], [Page ID], [Slot ID], [BeginTime], [EndTime], [Number of Locks], [Lock Information]
  9. FROM sys.fn_dblog(NULL,NULL)
  10. WHERE [Transaction ID] = '0000:000002f8'

结语

通过了解事务日志中所记录的内容,可以更方便我们去了解SQL Server所做的一些操作的执行过程。