sql_trace基本用法

sql_trace是oracle提供的一个非常好的跟踪工具，主要用来检查数据库的异常情况，通过跟踪数据库的活动，找到有问题的语句。

一、概述：
    SQL_TRACE是Oracle的一个非常强大的工具。打开SQL_TRACE就可以逐步捕获任何一个会话的数据库活动，或者捕获整个数据库的活动，并将数据库活动记录成跟踪文件。每次使用完之后需要关闭跟踪,否则会降低系统的性能。
    SQL_TRACE可以帮助诊断许多问题，其中包括：

二、用法：
   1、文件跟踪的分类：
      跟踪DBA可以采用两种方式进行跟踪：
    . 跟踪整个数据库实例。只需要简单的修改参数文件(pfile/spfile)参数 SQL_TRACE = TRUE ，然后重新启动数据库即可。在全局启用SQL_TRACE会导致所有进程的活动被跟踪，包括后台进程及所有用户进程,这样也会数据库导致性能下降比较明显。
    . 会话级跟踪。SQL_TRACE的通常使用方式是仅跟踪一个会话。被跟踪的会话可以是您自己的，也可以是其它用户的会话。如果是自己的会话，只需要在SQL*PLUS中运行一下命令即可：
      SQL> alter session set sql_trace = true;
      类似的如果取消对会话的跟踪，运行一下命令：
      SQL> alter session set sql_trace = false;
     
      如果需要跟踪一个特定的会话，首先需要获取会话的SID和Serial#，这些信息可以在视图V$SESSION中获得，一旦知道了这两个参数，就可以运行一下命令：
      SQL> execute SYS.dbms_system.set_sql_trace_in_session(13,9,true);
      同样也可以使用这个过程关闭会话跟踪：
    SQL> execute SYS.dbms_system.set_sql_trace_in_session(13,9,false);
 
  2、跟踪文件的位置：
     一旦为会话激活了SQL_TRACE，ORACLE就会在udump管理区创建跟踪文件，文件的目标位置由参数user_dump_dest来确定。每个操作都不会覆盖原来的文件，新的跟踪记录将会被追加到文件末尾。通常情况下，可以根据文件的修改时间判断目录下哪个文件是最新的文件。
   SQL> show parameter user_dump_dest;
  
   NAME                                 TYPE        VALUE
   ------------------------------------ ----------- ------------------------------
   user_dump_dest                       string      d:oracleadminora9iudump
  
   也可以通过以下SQL来确定文件名：
    
     select d.value||‘‘||lower(rtrim(i.instance, chr(0)))||‘_ora_‘||p.spid||‘.trc‘ trace_file_name 
   from 
   (
    select p.spid     
      from sys.v$mystat m,sys.v$session s,sys.v$process p
     where m.statistic# = 1
       and s.sid = m.sid
       and p.addr = s.paddr
   ) p,
   (
   select t.instance
     from sys.v$thread  t,sys.v$parameter  v 
    where v.name = ‘thread‘
      and ( v.value = 0 or t.thread# = to_number(v.value) )
   ) i, 
   (
   select value from sys.v$parameter where name = ‘user_dump_dest‘
   ) d ;

TRACE_FILE_NAME
   --------------------------------------------------------------------------------
  
   d:oracleadminora9iudumpora9i_ora_2060.trc  
 
 3、计时信息：
    为了最大限度的利用跟踪文件，应该打开计时标志,通过参数TIMED_STATISTICTS=TRUE进行设置，这样可以对每个SQL语句的执行时间等进行记录，这个功能对系统性能的负担很小。
    打开会话的计时信息:
    SQL> alter session set timed_statistics = true ;
    打开数据库系统的计时信息
    SQL> alter system set timed_statistics = true ;
 
 4、TKPROF：
    通过前三步的设置已经知道如何生成SQL跟踪文件了，ORACLE生成的跟踪文件阅读起来很困难（也就是易读性很差），可以看跟踪文件的一部分，执行以下SQL语句：
   SQL> select count(*) from sys_dept;
  
    COUNT(*)
   ----------
          16   
   执行完后，查看跟踪文件中这个语句的内容如下：
   
    PARSING IN CURSOR #1 len=31 dep=0 uid=62 oct=3 lid=62 tim=14727407741 hv=2200985491 ad=‘128e3820‘
   select count(*) from sys_dept
   END OF STMT
   PARSE #1:c=0,e=16348,p=1,cr=31,cu=0,mis=1,r=0,dep=0,og=4,tim=14727407735
   EXEC #1:c=0,e=24,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=4,tim=14727407814
   FETCH #1:c=0,e=15641,p=5,cr=7,cu=0,mis=0,r=1,dep=0,og=4,tim=14727423807
   =====================
   PARSING IN CURSOR #2 len=61 dep=0 uid=62 oct=47 lid=62 tim=14727508742 hv=3517412409 ad=‘12bbcff4‘
   begin :id := sys.dbms_transaction.local_transaction_id; end;
   END OF STMT
   PARSE #2:c=0,e=122,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=4,tim=14727508735
   EXEC #2:c=0,e=144,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=4,tim=14727508945
   =====================
   PARSING IN CURSOR #2 len=61 dep=0 uid=62 oct=47 lid=62 tim=14727587562 hv=3517412409 ad=‘12bbcff4‘
   begin :id := sys.dbms_transaction.local_transaction_id; end;
   END OF STMT
   PARSE #2:c=0,e=121,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=4,tim=14727587556
   EXEC #2:c=0,e=97,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=4,tim=14727587721
  
   这样不仅阅读麻烦，并且也有很多内容难以理解。ORACLE提供了一个格式化跟踪文件的工具 - TKPROF( Transient Kernel Profiler ),通过这个工具能将SQL文件转化为分析人员容易理解的格式。
   
    一般TKPROF工具的使用的简单方法,只用到了两个关键字:跟踪文件名和输出文件名 (TKPROF的具体请参阅其他资料):
    TKPROF <trace file> <output file>
   
    在命令行模式下运行(数据库在window2000下安装的)
    C:>tkprof D:oracleadminora9iudumpora9i_ora_2060.trc d:report.txt
   
    执行完后,在reprot.txt中查询刚才的语句内容如下:
    select count(*)
   from
   sys_dept
 
 
   call     count       cpu    elapsed       disk      query    current        rows
   ------- ------  -------- ---------- ---------- ---------- ----------  ----------
   Parse        1      0.00       0.01          1         31          0           0
   Execute      1      0.00       0.00          0          0          0           0
   Fetch        1      0.00       0.01          5          7          0           1
   ------- ------  -------- ---------- ---------- ---------- ----------  ----------
   total        3      0.00       0.03          6         38          0           1
  
   Misses in library cache during parse: 1
   Optimizer goal: CHOOSE
   Parsing user id: 62     
 
   通过设置tkprof的关键字[EXPLAIN = <username/password>],也可以在跟踪文件中增加SQL语句的执行计划：
  C:>tkprof D:oracleadminora9iudumpora9i_ora_2060.trc d:report.txt explain=test/test;  
  
  ********************************************************************************
 
  select count(*)
  from
   sys_dept
 
 
  call     count       cpu    elapsed       disk      query    current        rows
  ------- ------  -------- ---------- ---------- ---------- ----------  ----------
  Parse        2      0.00       0.01          1         31          0           0
  Execute      2      0.00       0.00          0          0          0           0
  Fetch        2      0.00       0.01          5         14          0           2
  ------- ------  -------- ---------- ---------- ---------- ----------  ----------
  total        6      0.00       0.03          6         45          0           2
 
  Misses in library cache during parse: 1
  Optimizer goal: CHOOSE
  Parsing user id: 62 
 
  Rows     Row Source Operation
  -------  ---------------------------------------------------
        1  SORT AGGREGATE
       16   TABLE ACCESS FULL SYS_DEPT