使用TKPROF格式化TRACE输出之“解剖麻雀”.docx

【TKPROF】使用TKPROF格式化TRACE输出之“解剖麻雀” 2009-10-21 11:26:54 《【SQL_TRACE】SQL优化及性能诊断好帮手》/519536/viewspace-616240中简单的提到了使用TKPROF工具格式化SQL_TRACE的输出信息的方法，并没有对输出的内容做比较清楚的描述，通过这个小文儿，展示一下TKPROF对trace文件格式化的魅力，这里只给出具体输出信息含义的描述，不展示真实的性能诊断案例。1.与之上次使用到的SQL_TRACE功能相比，我们这次将使用更高级的trace命令（10046）来做演示。我们先启用“高级”的10046事件生成一份待格式化的trace文件1）连接到普通用户secsys@ora10g conn sec/secConnected.2）先将timed_statistics参数设置为true，这样可以使TKPROF工具能提供更多的有意义的信息，方便性能诊断sec@ora10g alter session set timed_statistics=true;Session altered.3）“高级”之所在，我们这里启用10046的level 12对当前会话进行跟踪。（1）在此，给出10046各level的解释参考：level 1 to enable the standard SQL_TRACE facility (same as SQL_TRACE=TRUE)等同于标准的SQL_TRACE=TRUE；level 4 to enable SQL_TRACE and also capture bind variable values in the trace file启用SQL_TRACE，并捕捉跟踪文件中的绑定变量；level 8 to enable SQL_TRACE and also capture wait events into the trace file启用SQL_TRACE，并捕捉跟踪文件中的等待事件；level 12 to enable standard SQL_TRACE and also capture bind variables and wait events启用SQL_TRACE，并捕捉跟踪文件中的绑定变量和等待事件（捕获信息能力最强）。（2）对当前会话启用level 12的跟踪：sec@ora10g alter session set events 10046 trace name context forever, level 12;Session altered.（3）【补充】如果想使用这种高级方法对其他的会话进行跟踪的话，可以使用如下的SQL语句开启和关闭：开启：execute dbms_system.set_ev(sid,serial#,10046,12,);关闭：execute dbms_system.set_ev(sid,serial#,10046,0,);举例如下：A.获得sid, serial#的信息sys@ora10g select sid, serial# from v$session;B.开启sys@ora10g execute dbms_system.set_ev(543,1306,10046,12,);C.关闭sys@ora10g execute dbms_system.set_ev(543,1306,10046,0,);4）在该会话中运行一条具体的SQL语句，此处使用到的实验表t中包含1亿条数据。该SQL语句的执行情况将被细致的记录到trace文件中。sec@ora10g select count(*) from t;? COUNT(*)----------?1000000005）关闭当前会话的跟踪sec@ora10g alter session set events 10046 trace name context off;Session altered.2.在udump目录中找到刚刚生成的trace文件（关于如何快速获得生成的trace文件的方法有很多种，选择一个适合自己的就OK），并使用TKPROF对其进行格式化，然后打印一下全部输出，后面将对每一个输出项做一下“解剖麻雀”:)ora10g@secDB /oracle/app/oracle/admin/ora10g/udump$ tkprof ora10g_ora_21213.trc ora10g_ora_21213.prfTKPROF: Release .0 - Production on Wed Oct 21 10:04:40 2009Copyright (c) 1982, 2005, Oracle.? All rights reserved.ora