简介

本文将介绍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插入数据

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所做的一些操作的执行过程。