Oracle中的trace file

Oracle中的Trace文件

如果一个系统的执行效率比较低，一个比较好的方法是通过跟踪用户的会话并且使用tkprof工具使用排序功能格式化输出，从而找出有问题的SQL语句。

例如首先从os上利用top命令找到当前占用cpu资源最高的一个进程的PID号9999；

然后在数据库中根据PID号找到相应的sid和serial#

select s.sid,s.serial# from v$session s,v$process p where
s.paddr=p.addr and p.spid='9999';

然后通过exec
dbms_monitor.session_trace_enable(sid,serial#)开启trace；

最后利用tkprof察看trace输出。

一：开启Trace文件输出

可以通过以下方法开启Trace文件输出（需要ALTER SESSION系统权限）:

1) alter session/system set sql_trace=true

2) exec
dbms_monitor.session_trace_enable/dbms_monitor.database_trace_enable

3) alter session set events '10046 trace name context forever,
level 12'

二：Trace文件的位置

· 如果使用专用服务器连接，会在USER_DUMP_DEST参数指定的目录中生成跟踪文件。

· 如果使用共享服务器连接，则在BACKGROUND_DUMP_DEST参数指定的目录中生成跟踪文件。

Oracle 11g之前，可以通过如下语句获得Trace文件路径：

select c.value || '/' || d.instance_name || '_ora_' || a.spid ||
'.trc' trace

from v$process a, v$session b, v$parameter c,
v$instance d

where a.addr = b.paddr

and b.audsid =
userenv('sessionid')

and c.name =
'user_dump_dest'；

Oracle 11g之后，可以访问v$diag_info来获得Trace文件存放路径：

select * from v$diag_info where name='Default Trace File';

可以使用参数TRACEFILE_IDENTIFIER，为跟踪文件名增加一个可以惟一标识的串。例如：

alter session set tracefile_identifier='my_trace_file';

这样，生成的Trace文件名就会以my_trace_file.trc结尾。

三：利用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 sys=no sort=prsela,exeela,fchela

prsela elapsed time parsing

exeela elapsed time executing

fchela elapsed time fetching

2）输出结果格式

输出结果中，首先是头部内容。

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

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

例如下面的输出：

********************************************************************************

SQL ID: 0c07h414zr55p

Plan Hash: 1968341081

update emp set sal=2451

where

empno=7782

call
count
cpu
elapsed
disk
query
current
rows

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

Parse
2
0.01
0.00
0
0
0
0

Execute
2
0.00
3.71
0
3
7
2

Fetch
0
0.00
0.00
0
0
0
0

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

total
4
0.01
3.72
0
3
7
2

Misses in library cache during parse: 1

Optimizer mode: ALL_ROWS

Parsing user id: 86 (TONY)

Rows
Row Source Operation

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

0 UPDATE EMP (cr=1 pr=0 pw=0
time=0 us)

1 INDEX UNIQUE SCAN EMP_PK
(cr=1 pr=0 pw=0 time=0 us cost=0 size=26 card=1)(object id
73464)

Rows
Execution Plan

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

0 UPDATE
STATEMENT MODE: ALL_ROWS

0 UPDATE OF 'EMP'

1
INDEX MODE: ANALYZED (UNIQUE
SCAN) OF 'EMP_PK' (INDEX

(UNIQUE))

********************************************************************************

执行统计有如下的几列：

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语句数量。

转自：http://blog.csdn.net/fw0124/article/details/6899162

以下来自另一文

Oracle trace文件学习

跟踪文件(trace file)能提供调试信息，服务器遇到问题时，它会生成一个包含大量诊断信息的跟踪文件。

如果开发人员设置了sql_trace=true，服务器就会生成一个包含性能相关信息的跟踪文件。我们之所以可以使用这些跟踪文件，是因为是一个允许充分测量的软件。编写内核的程序员在内核中放入了调试代码，而且调试代码相当多，这些调试代码是被程序员有意留在内核中的。

Oracle数据库都是可以充分测量的。数据库中这种测量性反映在以下几个方面：

1.V$视图：大多数V$视图都包含“调试”信息。V$WAITSTAT、V$SESSION_EVENT还有其他许多V$视图之所以存在，就是为了让我们知道内核内部到底发生了什么。

2.审计命令：利用这个命令，你能指定数据库要记录哪些事件以便日后分析。

3.资源管理器(DBMS_RESOURCE_MANAGER)：这个特性允许你对数据库中的资源（CPU、IO等）实现微管理。正是因为数据库能访问描述资源使用情况的所有运行时统计信息，所以才可能有资源管理器。

4.Oracle“事件”：基于oracle事件，能让oracle生成所需的跟踪或诊断信息。

5.DBMS_TRACE：这是PL/SQL引擎中的一个工具，它会全面地记录存储过程的调用树、所产生的异常以及遇到的错误。

6.数据库事件触发器：这些触发器(ON
SERVERERROR)允许你监控和记录你觉得“意外”或非正常的情况。例如，可以记录发生“临时用尽”错误时正在运行的.

7.SQL_TRACE：这个SQL跟踪工具还可以采用一种扩展方式使用，即通过10046 Oracle事件。

Oracle 9i2和10G.1两个版本之间增加的测量代码量就相当的多。

通常有两类跟踪文件，对这两类跟踪文件的处理完全不同。

1.你想要的跟踪文件：例如启用sql_trace=true选项的结果，其中包含有关会话的诊断信息，有助于你调整应用，优化应用的性能，并诊断出遭遇的瓶颈。

2.你不想要的跟踪文件，但是由于出现了以下错误，服务器会自动生成这些跟踪文件。

如：ora-00600 "internal error" ora-03113 "end of file on
communication channel

一：请求的跟踪文件

想要的跟踪文件通常都是因为设置了sql_trace=true生成的结果，或者是通过10046事件使用扩展的跟踪工具生成的，如下所示：

alter session set events '10046 trace name context forever,level
12';

1.文件位置

不论是使用sql_trace还是扩展的跟踪工具，Oracle都会在数据库服务器主机的以下两个位置生成一个跟踪文件：

如果使用专用服务器连接，会在USER_DUMP_DEST参数指定的目录中生成跟踪文件。

如果使用共享服务器连接，则在BACKGROUND_DUMP_DEST参数指定的目录中生成跟踪文件。

要想知道跟踪文件放在哪能里，可以通过

select name,value from v$parameter where name like
'%dump_dest%';

或者通过show parameter user_dump_dest或background_dump_dest参数来显示。

如果出现严重的oracle内部错误，或是oracle
support要求你生成一个跟踪文件来得到额外的调试信息，CORE_DUMP_DEST参数则定义了此时这个“内核”文件应该放在哪里。

一般而言，我们只对后台和用户转储目录感兴趣。

如果你无法访问V$PARAMETER视图，可以使用DBMS_UTILITY来访问大多数参数的值，从下面的例子可以看出，需要create
session权限：

create user least_privs identified by least_privs;

grant create session to least_privs;

connect least_privs/least_privs

declare

l_string varchar2(255);

l_dummy number;

begin

l_dummy := dbms_utility.get_parameter_value

('background_dump_dest',l_dummy,l_string);

dbms_output.put_line('background: ' || l_string);

l_dummy := dbms_utility.get_parameter_value

('user_dump_dest',l_dummy,l_string);

dbms_output.put_line('user: '
|| l_string);

end;

/

2.命名约定

Oracle中跟踪文件的命令约定总在变化，不过，如果把你的系统上的跟踪文件名作为示例，应该很容易地看出这些命名都有一个模板.

如：ora10g_ora_24574.trc

第一个为oracle_sid，下一个为ora，跟踪文件名的数字是专用服务器的进程ID，可以从V$PROCESS视图得到。

在实际中（假设使用专用服务器模式），需要访问4个视图：

V$PARAMETER：找到USER_DUMP_DEST指定的跟踪文件位置。

V$PROCESS：查找进程ID。

V$SESSION：正确地标识其他视图中的会话信息。

V$INSTANCE：得到oracle_sid。

前面提到过，可以使用DBMS_UTILITY来找到位置，而且通常你“知道”oracle_sid，所以从理论上讲只需要访问V$SESSION和V$PROCESS，但是，为了便于使用，这4个视图你可能都想访问。

以下查询可以生成跟踪文件名(此方法只适用于专用服务器模式)：

alter session set sql_trace=true;

select c.value || '/' || d.instance_name || '_ora_' || a.spid ||
'.trc' trace

from v$process a,v$session b,v$parameter c,v$instance d

where a.addr = b.paddr and b.audsid = userenv('sessionid') and
c.name = 'user_dump_dest'

/

windows上要反/换成\,9I中只需使用如下查询，

select c.value || 'ora_' || a.spid || '.trc'

3.对跟踪文件加标记

对跟踪文件加标记，这样即使用无权访问v$process和v$session也能找到跟踪文件。

假设你能读取user_dump_dest目录，就可以使用会话参数trancefile_identifier,采用这种方法，可以为跟踪文件名增加一个可以惟一标识的串，例如：

alter session set tracefile_identifier = 'Look_For_Me';

alter session set sql_trace = true;

!ls /home/ora10g/admin/ora10g/udump/*Look_For_Me*

/home/ora10g/admin/ora10g/udump/ora10g_ora_24676_Look_For_Me.trc

可以看到跟踪文件还是采用标识的_ora_格式命名，这里还有我们为它指定的一个惟一的串，这样就很容易地找到“我们的”跟踪文件名。

二：针对内部错误生成的跟踪文件，这类文件通常不是我们关心的，可以不写了。

三：修改跟踪文件（change tracking file）是一个可选的文件，这是oracle
10g企业版中新增的，这个文件惟一的目的是跟踪自上一个增量以来哪些块已经修改。

采用这种方式，管理器(recovery
manager,rman)工具就能只备份确实有变化的数据库块，而不必读取整个数据库。

在oracle 10G之前的版本，要完成增量备份，必须读取整个数据库文件，查找自上一次增量备份以来修改的块。

如果有一个1T的数据库，只增加了500M的新数据，增量备份就必须读取1T的数据，在其中找出要备份的500M新信息，尽管增量备份存储的数据确实少得多，但它还是要读取整个数据库。

在10G企业版中，oracle运行时，如果块被修改，oracle可能会维护一个文件，告诉RMAN哪些块已经修改。创建这个修改跟踪文件的过程相当简单，只需要通过ALTER
命令就可以完成：

alter database enable block change tracking using file
'/home/ora10g/product/10.1.0/oradata/ora10g/changed_blocks.bct';

要关闭和删除块修改跟踪文件，还要用一次alter database命令：

alter database disable block change tracking;

注意这个命令实际上会清除块修改跟踪文件。它不只是禁用这个特性，而是连文件也一并删除了。可以采用archivelog或noarchivelog模式再次启用这个新的块修改跟踪特性，不过要记住noarchivelog模式的数据库中并不保留每天生成的重做日志，所以一旦介质出现故障，所有修改都将无法恢复.
noarchivelog总有一天会丢失数据，建议使用archivelog模式。

转自：http://hi.baidu.com/bystander1983/item/fbb40513d27944071994ec3d

以下来自另一文

Oracle Trace文件生成及查看

一：Trace file简介:

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

二：怎么生成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'

我本机的查找结果是:C:\ORACLE\PRODUCT\10.2.0\ADMIN\ORA102\UDUMP.

三：怎么更改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)

四：怎么查看trace文件:

如果直接看trace文件是很难看懂的.就是下面的样子(我只是随便复制一段):

1319423003070764

=====================

PARSING IN CURSOR #2 len=90 dep=1 uid=0 oct=3 lid=0
tim=1319423003070864 hv=673844243 ad='7ecb9458'
sqlid='9g485acn2n30m'

select col#,intcol#,reftyp,stabid,expctoid from refcon$ where
obj#=:1 order by intcol# asc

END OF STMTPARSE
#2:c=0,e=56,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=2544153582,tim=1319423003070864

BINDS #2: Bind#0oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00
pre=00

oacflg=08 fl2=0001 frm=00 csi=00 siz=24 off=0
kxs

我们可以用oracle自带的工具TKPROF转化后再查看trace文件.转化后是下面的样子:

SQL ID : 0zzk39z279q41

SELECT version

FROM

product_component_version WHERE product LIKE 'Oracle%'

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
1
0.00
0.00
0
0
0
1

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

total
3
0.00
0.00
0
0
0
1

Misses in library cache during parse: 1

Optimizer mode: ALL_ROWS

Parsing user id: 69

Rows
Row Source Operation

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

1 VIEW
PRODUCT_COMPONENT_VERSION (cr=0 pr=0 pw=0 time=0 us cost=2 size=168
card=2)

1 SORT UNIQUE (cr=0 pr=0 pw=0
time=0 us cost=2 size=110 card=2)

1
UNION-ALL (cr=0 pr=0 pw=0 time=0 us)

0
FIXED TABLE FULL X$VERSION (cr=0 pr=0 pw=0 time=0 us cost=0 size=55
card=1)

1
FIXED TABLE FULL X$VERSION (cr=0 pr=0 pw=0 time=0 us cost=0 size=55
card=1)

********************************************************************************

转自：http://blog.csdn.net/weiwenhp/article/details/6932835