sql_trace生成及使用tkprof查看trace file

1.Trace file简介:

Trace file（追踪文件)是以trc为后续的文本文件,它记录了各种sql操作及所消耗的时间等,根据trace文件我们就可以了解哪些sql导致了系统的性能瓶颈，进而采取恰当的方式调优.

2.怎么生成trace file:

1. 首先用sqlplus登陆Oracle.

show parameter sql_trace

Name          Type        Value

-------------------------------------------

sql_trace     boolean      false

如果value是false表示系统当前不会产生trace文件.采取如下操作让系统产生trace文件:

alter session set sql_trace=true;

或者:alter system set sql_trace=true;

2.执行一些sql语句后.停止产生trace文件.alter session(或system)  set sql_trace=false.

3.trace文件所在的默认路径.SELECT VALUE  FROM V$PARAMETER WHERE NAME = 'user_dump_dest'

3.怎么更改trace文件的保存目录:

如果是oracle 11g 以下的版本则:alter system set user_dump_dest = 'd:\oracle\trace';(注意:trace文件就直接生成在trace目录下)

如果是oracle 11g.则alter system set user_diagnostic_dest = 'd:\oracle\trace';(注意:trace文件不会直接生成在trace目录下.trace目录下会生成其他很多目录.

trace文件的具体目录是:d:\oracle\trace\diag\rdbms\orli11r2\orli11r2\trace.其中的orli11r2是SID)

4.怎么查看trace文件:

利用tkprof工具分析Trace文件

可以利用tkprof工具分析Trace文件，产生一个更加清晰合理的输出结果。tkprof可以在$ORACLE_HOME/bin下面找到。

1）命令格式

命令格式为：

tkprof tracefile outputfile [explain= ] [table= ] [print= ] [insert= ] [sys= ] [sort= ]

参数说明：

tracefile：要分析的trace文件 

outputfile：格式化后的文件

explain=user/password@connectstring 

table=schema.tablename 

    上述两个参数是一起使用的，explain指示tkprof要为在跟踪文件中找到的每个SQL语句提供一个执行计划。

    这是通过执行SQL语句EXPLAIN PLAN通过连接数据库对在trace文件中出现的每条sql语句查看执行计划，并将之输出到outputfile中。

    指定的table名将提供给EXPLAIN PLAN语句。

print=n：只列出最初N个sql执行语句，默认是无限制的，只有在和参数sort一起使用的时候才有意义

insert=filename：会产生一个sql文件，运行此文件可将收集到的数据insert到数据库表中
sys=no：sys用户运行的SQL语句(例如，解析操作阶段对数据字典的递归查询)不输出到输出文件中。

record=filename：可将非嵌套执行的sql语句过滤到指定的文件中去 

waits=yes|no：是否统计任何等待事件，默认是yes 

aggregate=yes|no：是否将相同sql语句的执行信息合计起来，默认为yes 

sort= option：设置排序选项，可以用逗号分隔多个选项。默认是跟踪文件中发现的SQL顺序。具体选项可以查看tkprof的命令帮助输出得到。

例如：

tkprof <tracefile> <outputfile> sys=no sort=prsela,exeela,fchela

    prsela  elapsed time parsing

    exeela  elapsed time executing

    fchela  elapsed time fetching

只指定原TRC文件及生成文件位置：

tkprof /u01/diag/rdbms/bysrac/bysrac1/trace/bysrac1_ora_15241_TEST9_1.trc /home/oracle/test9_1.trc

2）输出结果格式
输出结果中，首先是头部内容。

之后针对每个SQL语句提供如下信息：SQL 语句文本、执行统计、关于解析的信息、执行计划以及等待事件。

执行计划以及等待事件是可选的，只有存储在跟踪文件中才会出现。

例如下面的输出：

SQL ID: 6ns41jwh1fz2u

Plan Hash: 1544281142

select *

from

 bys.test9 where object_id <20000

call     count       cpu    elapsed       disk      query    current        rows

------- ------  -------- ---------- ---------- ---------- ----------  ----------

Parse        1      0.00       0.00          0          0          0           0

Execute      1      0.00       0.00          0          0          0           0

Fetch     1129      0.09       0.65          0       1342          0       16914

------- ------  -------- ---------- ---------- ---------- ----------  ----------

total     1131      0.09       0.65          0       1342          0       16914

Misses in library cache during parse: 0

Optimizer mode: ALL_ROWS

Parsing user id: SYS

Rows     Row Source Operation

-------  ---------------------------------------------------

  16914  TABLE ACCESS FULL TEST9 (cr=1342 pr=0 pw=0 time=92380 us cost=67 size=3484845 card=16835)

Elapsed times include waiting on following events:

  Event waited on                             Times   Max. Wait  Total Waited

  ----------------------------------------   Waited  ----------  ------------

  SQL*Net message to client                    1129        0.00          0.01

  Disk file operations I/O                        1        0.00          0.00

  ges message buffer allocation                  21        0.00          0.00

  gc cr multi block request                      25        0.04          0.44

  SQL*Net message from client                  1129        0.00          0.35

执行统计有如下的几列：

count：表示执行的数据库调用数量。

cpu：表示处理数据调用花去的CPU时间，以秒为单位。

elapsed：是处理数据库调用花费的总的时间，以秒为单位，如果这个值比CPU时间高，下一节关于执行统计中的等待事件会提供在等待的资源或同步点。

disk：表示物理读的数据块数量。要当心，这不是物理I/O操作的数量，物理I/O操作数在关于等待事件一节给出。如果这个值大于逻辑读的数量(disk > query +current)，这意味着数据块填充进了临时表空间。

query：是在一致性模式（consistent mode）下从高速缓存逻辑读取的块数量。通常，这类型的逻辑读用作查询。 

current：代表在当前模式下从高速缓存逻辑读取的块数量。通常，这类逻辑读被INSERT、DELETE、MERGE以及UPDATE等语句所使用。

rows：代表处理的数据行数量。对于查询来说，这就是获取的行数量。对于INSERT、DELETE、MERGE以及UPDATE 等语句来说，这是所影响的行数量。

关于解析的信息开始两行Misses in library cache during parse和Misses in library cache during execute提供了发生在解析和执行调用阶段的硬解析数量。

如果在执行调用时没有硬解析发生，Misses in library cache during execute这一行将不存在。

接下来是优化器模式以及用于解析SQL语句的用户。

执行计划分为两部分，第一部分称为行源操作(Row Source Operation )，是游标关闭且开启跟踪情况下写到跟踪文件中的执行计划。这意味着如果应用程序不关闭游标而重用它们的话，不会有新的针对重用游标的执行计划写入到跟踪文件中。第二部分，叫做执行计划 (Execution Plan)，是由指定了explain参数的TKPROF生成的。既然这是随后生成的，所以和第一部分不一定完全匹配。万一你看到不一致，前者是正确的。

两个执行计划都通过Rows列提供执行计划中每个操作返回的行数（不是处理的--要注意）。 

对于每个行源操作来说，可能还会提供如下的运行时统计： 

cr是一致性模式下逻辑读出的数据块数。

pr是从磁盘物理读出的数据块数。

pw是物理写入磁盘的数据块数。

time是以微秒表示的总的消逝时间。要注意根据统计得到的值不总是精确的。实际上，为了减少开销，可能用了采样。

cost是操作的评估开销。这个值只有在Oracle 11g才提供。

size是操作返回的预估数据量(字节数)。这个值只有在Oracle 11g才提供。 

card是操作返回的预估行数。这个值只有在Oracle 11g才提供。

输出文件的结尾给出了所有关于跟踪文件的信息。首先可以看到跟踪文件名称、版本号、用于这个分析所使用的参数sort的值。然后，给出了所有会话数量与SQL语句数量。