分享个SQLServer profiler 的一个技巧吧。很早用过,忘记总结了,现在再用时记录下来。
当启用 SQLServer profiler 跟踪sql语句的时候,是非常方便的,同时也可以按照各个维度筛选跟踪。但是对于长时间跟踪,一直打开着profiler界面不是很好。有一个技巧是可以把profiler 的跟踪设置导出成sql 脚本,脚本可以在后台执行。以跟踪慢查询为例。
【堵塞跟踪】
1. 首先打开profiler 跟踪堵塞语句,事件选择 Blocked process report ,再把其他的事件都去掉。
2. 分别执行以下语句,模拟堵塞情况。
-
- BEGIN TRAN
- UPDATE dbo.AAA SET Title='KK'
- WAITFOR DELAY '00:00:20'
- COMMIT TRAN
-
-
- UPDATE dbo.AAA SET Title='KK'
3. 几秒钟后,profiler 捕获到了堵塞信息。
4. 查看XML格式的堵塞信息。<blocked-process> 块是被堵塞的信息,<blocking-process> 为堵塞其他进程的信息。
- <blocked-process-report>
- <blocked-process>
- <process id="process271931c8" taskpriority="0" logused="0" waitresource="KEY: 13:72057595528151040 (f2008a1296d8)"
- waittime="9313" ownerId="923979" transactionname="UPDATE" lasttranstarted="2015-10-19T16:54:36.753"
- XDES="0x53bec08" lockMode="U" schedulerid="1" kpid="7712" status="suspended" spid="55" sbid="0" ecid="0"
- priority="0" trancount="2" lastbatchstarted="2015-10-19T16:54:36.753" lastbatchcompleted="2015-10-19T16:33:12.240"
- clientapp="Microsoft SQL Server Management Studio - 查询" hostname="kk" hostpid="4204" loginname="kk\Administrator"
- isolationlevel="read committed (2)" xactid="923979" currentdb="13" lockTimeout="4294967295" clientoption1="671090784" clientoption2="390200">
-
- <executionStack>
- <frame line="1" stmtstart="36" sqlhandle="0x020000005733991f90e1d1e1f620bc49ef0224e73cc0dc81"/>
- <frame line="1" sqlhandle="0x020000008d15b82d9936449bac7b1e417abd9ca11e87c9b3"/>
- </executionStack>
- <inputbuf>
- UPDATE dbo.AAA SET Title='KK' </inputbuf>
- </process>
- </blocked-process>
- <blocking-process>
- <process status="suspended" waittime="10501" spid="69" sbid="0" ecid="0" priority="0" trancount="1"
- lastbatchstarted="2015-10-19T16:54:35.563" lastbatchcompleted="2015-10-19T16:43:28.033" lastattention="2015-10-19T15:37:32.157"
- clientapp="Microsoft SQL Server Management Studio - 查询" hostname="kk" hostpid="4204" loginname="kk\Administrator"
- isolationlevel="read committed (2)" xactid="923974" currentdb="13" lockTimeout="4294967295" clientoption1="671090784" clientoption2="390200">
-
- <executionStack>
- <frame line="3" stmtstart="102" stmtend="152" sqlhandle="0x020000006a0d703b95612db79d614f608d92db0ec58bcfb3"/>
- </executionStack>
- <inputbuf>
- BEGIN TRAN
- UPDATE dbo.AAA SET Title='KK'
- WAITFOR DELAY '00:00:20'
- COMMIT TRAN </inputbuf>
- </process>
- </blocking-process>
- </blocked-process-report>
但是这样跟踪的确不方便,可以试试以下这种方法。
1. 在当前 profiler 设置中导出SQL 脚本
2. 更改了创建跟踪(sp_trace_create)一些跟踪信息。最终脚本如下
-
- declare @rc int
- declare @traceid int
- declare @options int
- declare @tracefile nvarchar(500)
- declare @maxfilesize bigint
- declare @endtime datetime
- declare @filecount int
- set @options = 2
- set @tracefile = N'E:\BlockedTraceResult\BlockedTraceName'
- set @maxfilesize = 20
- set @endtime = DATEADD(D,1,GETDATE())
- set @filecount = 5
-
-
- exec @rc = sp_trace_Create @TraceID output,@options,@tracefile,@maxfilesize,@endtime,@filecount
- if (@rc != 0) goto error
-
-
-
-
- declare @on bit
- set @on = 1
- exec sp_trace_setevent @TraceID, 137, 15, @on
- exec sp_trace_setevent @TraceID, 137, 12, @on
- exec sp_trace_setevent @TraceID, 137, 1, @on
- exec sp_trace_setevent @TraceID, 137, 13, @on
- exec sp_trace_setevent @TraceID, 137, 14, @on
-
-
-
- declare @intfilter int
- declare @bigintfilter bigint
-
-
- exec sp_trace_setstatus @TraceID, 1
-
-
- select TraceID=@TraceID
- goto finish
-
- error:
- select ErrorCode=@rc
-
- finish:
- go
3. 执行上面的脚本,跟踪即可在后台运行,跟踪记录记录在文件中。
4. 要查看跟踪内容,可以双击跟踪文件以 profiler 打开查看。或者可以用sql 语句查看其跟踪设置和跟踪记录内容。
- select * from sys.traces
- select * from ::fn_trace_getinfo(0)
-
- select * from ::fn_trace_gettable('E:\BlockedTraceResult\BlockedTraceName.trc',default)
-
- select ServerName,EventClass,StartTime,TextData,CONVERT(XML,TextData) XMLTextData
- from ::fn_trace_gettable('E:\BlockedTraceResult\BlockedTraceName.trc',default)
5. 停止、启用和删除跟踪
- select id,status from sys.traces
-
-
-
- exec sp_trace_setstatus 3,0
- exec sp_trace_setstatus 3,1
- exec sp_trace_setstatus 3,2
6. 堵塞时间设置(多少秒才算是堵塞)
-
- exec sp_configure N'blocked process threshold (s)'
-
-
- exec sp_configure N'blocked process threshold (s)',5
- reconfigure
注意:
这里跟踪的只是堵塞,如果一个语句执行30秒,虽然执行时间较长,但是没有堵塞其他语句,也是跟踪不到的。所以有的慢查询是会漏掉的。
如果设置5秒捕获堵塞,同一个查询如果堵塞了10秒,将会跟踪到2次相同的信息,也就是每5秒捕获一次。
【慢查询、堵塞跟踪】
同样步骤,跟踪慢查询只要 跟踪存储过程或者TSQL的完成情况,如下图所示,跟踪 RPC:Complited 和 SQL:BacthComplited ,只有跟踪 Complited 才有时间字段,starting 是没有时间的,所以starting 没必要跟踪了。
筛选跟踪时间,这里设置只跟踪记录大于等于3000毫秒的语句。
导出脚本,语句如下:
- declare @rc int
- declare @traceid int
- declare @options int
- declare @tracefile nvarchar(500)
- declare @maxfilesize bigint
- declare @endtime datetime
- declare @filecount int
- set @options = 2
- set @tracefile = N'E:\SlowQueryTraceResult\SlowTraceName'
- set @maxfilesize = 20
- set @endtime = NULL
- set @filecount = 5
-
- exec @rc = sp_trace_Create @TraceID output,@options,@tracefile,@maxfilesize,@endtime,@filecount
- if (@rc != 0) goto error
-
-
-
-
- declare @on bit
- set @on = 1
- exec sp_trace_setevent @TraceID, 10, 15, @on
- exec sp_trace_setevent @TraceID, 10, 16, @on
- exec sp_trace_setevent @TraceID, 10, 1, @on
- exec sp_trace_setevent @TraceID, 10, 17, @on
- exec sp_trace_setevent @TraceID, 10, 18, @on
- exec sp_trace_setevent @TraceID, 10, 12, @on
- exec sp_trace_setevent @TraceID, 10, 13, @on
- exec sp_trace_setevent @TraceID, 10, 6, @on
- exec sp_trace_setevent @TraceID, 10, 14, @on
- exec sp_trace_setevent @TraceID, 12, 15, @on
- exec sp_trace_setevent @TraceID, 12, 16, @on
- exec sp_trace_setevent @TraceID, 12, 1, @on
- exec sp_trace_setevent @TraceID, 12, 17, @on
- exec sp_trace_setevent @TraceID, 12, 6, @on
- exec sp_trace_setevent @TraceID, 12, 14, @on
- exec sp_trace_setevent @TraceID, 12, 18, @on
- exec sp_trace_setevent @TraceID, 12, 12, @on
- exec sp_trace_setevent @TraceID, 12, 13, @on
-
-
-
- declare @intfilter int
- declare @bigintfilter bigint
-
- set @bigintfilter = 3000000
- exec sp_trace_setfilter @TraceID, 13, 0, 4, @bigintfilter
-
-
- exec sp_trace_setstatus @TraceID, 1
-
-
- select TraceID=@TraceID
- goto finish
-
- error:
- select ErrorCode=@rc
-
- finish:
- go
测试跟踪,设置事务执行大于3秒钟:
- BEGIN TRAN
- SELECT * FROM dbo.TestTab
- WAITFOR DELAY '00:00:03'
- ROLLBACK TRAN
跟踪结果:
- select ServerName,EventClass,StartTime,TextData,CONVERT(XML,TextData) XMLTextData
- from ::fn_trace_gettable('E:\SlowQueryTraceResult\SlowTraceName.trc',default)
注意:
与堵塞一样,如果一条语句可能执行不到1秒钟,但是资源被其他进程占用了,导致这条语句等待了10秒,这条语句也同样被输出。
跟踪的语句只有执行完成才能跟踪到,正在执行的语句是无法跟踪到的。
对于当前正在执行的,可以用系统 DMV 视图查看,如 master.dbo.sysprocesses 或者 sys.dm_exec_requests 等。
转载:http://blog.csdn.net/kk185800961/article/details/49252037