TKPROF的使用簡介

楊奇龍發表於2010-03-29

自己摸索了一箇中午,分享給大家,其中關於tkprof的簡介為摘錄。
Tkprof是一個用於分析Oracle跟蹤檔案並且產生一個更加清晰合理的輸出結果的可執行工具。如果一個系統的執行效率比較低,一個比較好的方法是透過跟蹤使用者的會話並且使用Tkprof工具使用排序功能格式化輸出,從而找出有問題的SQL語句。
TKPROF 命令語法:
TKPROF  filename1, filename2 [ SORT  = [ opion][,option] ]
  [  PRINT = integer ]
  [ AGGREGATE  = [ YES | NO ] ]
  [ INSERT = filename3 ]
  [ SYS = [ YES | NO ]  ]
  [  [ TABLE = schema.table ] | [ EXPLAIN = user/password ]  ]
  [  RECORD = filename ]
filename1  指定的輸入檔案,可以是多個檔案聯起來。
Filename2  格式化輸出檔案。
SORT     在輸出到輸出檔案前,先程式排序。如果省去,則按照實際使用的順序輸出到檔案中。排序選項有以下多種:
  prscnt  number of times parse was called
  prscpu  cpu time parsing
  prsela  elapsed time parsing
  prsdsk  number of disk reads during parse
  prsqry  number of buffers for consistent read during parse
  prscu   number of buffers for current read during parse
  prsmis  number of misses in library cache during parse
  execnt  number of execute was called
  execpu  cpu time spent executing
  exeela  elapsed time executing
  exedsk  number of disk reads during execute
  exeqry  number of buffers for consistent read during execute
  execu   number of buffers for current read during execute
  exerow  number of rows processed during execute
  exemis  number of library cache misses during execute
  fchcnt  number of times fetch was called
  fchcpu  cpu time spent fetching
  fchela  elapsed time fetching
  fchdsk  number of disk reads during fetch
  fchqry  number of buffers for consistent read during fetch
  fchcu   number of buffers for current read during fetch
  fchrow  number of rows fetched
  userid  userid of user that parsed the cursor
PRINT        只列出輸出檔案的第一個integer 的SQL語句。預設為所有的SQL語句。
AGGREGATE    如果= NO ,則不對多個相同的SQL進行彙總。
INSERT       SQL 語句的一種,用於將跟蹤檔案的統計資訊儲存到資料庫中。在TKPROF建立指令碼後,在將結果輸入到資料庫中。
SYS         禁止或啟用 將SYS使用者所釋出的SQL語句列表到輸出檔案中。
TABLE       在輸出到輸出檔案前,用於存放臨時表的使用者名稱和表名。
EXPLAIN     對每條SQL 語句確定其執行規劃。並將執行規劃寫到輸出檔案中。
其中比較有用的一個排序選項是fchela,即按照elapsed time fetching來對分析的結果排序(記住要設定初始化引數timed_statistics=true),生成的檔案將把最消耗時間的sql放在最前面顯示。另外一個有用的引數就是sys,這個引數設定為no可以阻止所有以sys使用者執行的sql被顯示出來,這樣可以減少分析出來的檔案的複雜度,便於檢視。
對Tkprof命令輸出的解釋:
首先解釋輸出檔案中列的含義:
CALL:每次SQL語句的處理都分成三個部分
Parse:這步將SQL語句轉換成執行計劃,包括檢查是否有正確的授權和所需要用到的表、列以及其他引用到的物件是否存在。
Execute:這步是真正的由Oracle來執行語句。對於insert、update、delete操作,這步會修改資料,對於select操作,這步就只是確定選擇的記錄。
Fetch:返回查詢語句中所獲得的記錄,這步只有select語句會被執行。
COUNT:這個語句被parse、execute、fetch的次數。
CPU:這個語句對於所有的parse、execute、fetch所消耗的cpu的時間,以秒為單位。
ELAPSED:這個語句所有消耗在parse、execute、fetch的總的時間。
DISK:從磁碟上的資料檔案中物理讀取的塊的數量。一般來說更想知道的是正在從快取中讀取的資料而不是從磁碟上讀取的資料。
QUERY:在一致性讀模式下,所有parse、execute、fetch所獲得的buffer的數量。一致性模式的buffer是用於給一個長時間執行的事務提供一個一致性讀的快照,快取實際上在頭部儲存了狀態。
CURRENT:在current模式下所獲得的buffer的數量。一般在current模式下執行insert、update、delete操作都會獲取buffer。在current模式下如果在快取記憶體區發現有新的快取足夠給當前的事務使用,則這些buffer都會被讀入了快取區中。
ROWS: 所有SQL語句返回的記錄數目,但是不包括子查詢中返回的記錄數目。對於select語句,返回記錄是在fetch這步,對於insert、update、delete操作,返回記錄則是在execute這步。
Tkprof的使用步驟基本上遵循以下幾步:
1、設定TIMED_STATISTICS為True,可以在會話級別,也可以在例項級別。
會話級:
SQL> alter session set timed_statistics=True;
例項級:
SQL> alter system set timed_statistics=True scope=both;
2、 設定SQL_TRACE,可以在會話級,也可以在資料庫級。
會話級:
SQL> alter session set sql_trace=true;
或者:
SQL>EXEC DBMS_SYSTEM.SET_SQL_TRACE_IN_SESSION(SID,SERIAL#,TRUE);
例項級:
SQL> alter system set sql_trace=true scope=both;
下面兩個例子說明一下具體的用法:


SQL> create table testlib  as select object_name noindex,object_name idx from dba_objects;

表已建立。

SQL> alter session set sql_trace=true;

會話已更改。

SQL> select count(*) from testlib;

  COUNT(*)                                                                    
----------                                                                    
     69304                                                                    

SQL> select count(*) from testlib;

  COUNT(*)                                                                    
----------                                                                    
     69304                                                                    

SQL> select count(*) from testlib;

  COUNT(*)                                                                    
----------                                                                    
     69304                                                                    

SQL> alter session set timed_statistics=true;

會話已更改。

SQL> select count(*) from testlib;

  COUNT(*)                                                                    
----------                                                                    
     69304                                                                    

SQL> alter session set sql_trace= false;

會話已更改。

SQL> select username,sid,serial# from v$session where username='system';

//此sql語句未執行。

SQL> select username,sid,serial# from v$session where username='system';
USERNAME                              SID    SERIAL#
------------------------------ ---------- ----------
GDSBDW                                 37      27544
SQL> select 'dss_ora_'||spid||'.trc' from v$process where addr = (select paddr f
rom v$session where sid=37);
'DSS_ORA_'||SPID||'.TRC'
------------------------
orcl_ora_3040.trc

也可以用下面的sql語句獲得trace檔案的所在位置

 SQL>  select
  2  d.value||'/'||lower(rtrim(i.instance,chr(0)))||'_ora_'||p.spid||'.trc' trace_file_name
  3  from
  4  (select p.spid
  5  from sys.v$mystat m, sys.v$session s,sys.v$process p
  6  where m.statistic#=1 and s.sid=m.sid and p.addr=s.paddr) p,
  7  (select
  8  t.instance from sys.v$thread t,sys.v$parameter v
  9  where v.name ='thread' and (v.value =0 or t.thread#= to_number(v.value))) i,
 10* (select value from sys.v$parameter where name ='user_dump_dest') d


TRACE_FILE_NAME                                                               
--------------------------------------------------------------------------------
f:\app\administrator\diag\rdbms\orcl\orcl\trace/orcl_ora_3040.trc

          
--使用tkprof分析trace檔案
C:\Documents and Settings\Administrator>tkprof  f:\app\administrator\diag\rdbms\orcl\orcl\trace/orcl_ora_4020.trc   f:\4020.trc.txt  aggregate=yes sys=no waits=yes sort=fchela
--tkprocf輸出了以下檔案:f:\4020.trc.txt

TKPROF: Release 11.1.0.6.0 - Production on 星期一 3月 29 13:50:42 2010

Copyright (c) 1982, 2007, Oracle.  All rights reserved.

Trace file: f:\4020.trc
Sort options: fchela
********************************************************************************
count    = number of times OCI procedure was executed
cpu      = cpu time in seconds executing
elapsed  = elapsed time in seconds executing
disk     = number of physical reads of buffers from disk
query    = number of buffers gotten for consistent read
current  = number of buffers gotten in current mode (usually for update)
rows     = number of rows processed by the fetch or execute call

 

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

OVERALL TOTALS FOR ALL NON-RECURSIVE STATEMENTS

call     count       cpu    elapsed       disk      query    current        rows
------- ------  -------- ---------- ---------- ---------- ----------  ----------
Parse        6      0.01    5403.67          0          1          0           0
Execute      7      0.00       0.00          0          0          0           0
Fetch        8      0.03       0.07        137       2080          0           4
------- ------  -------- ---------- ---------- ---------- ----------  ----------
total       21      0.04    5403.75        137       2081          0           4

Misses in library cache during parse: 2
Misses in library cache during execute: 1


OVERALL TOTALS FOR ALL RECURSIVE STATEMENTS

call     count       cpu    elapsed       disk      query    current        rows
------- ------  -------- ---------- ---------- ---------- ----------  ----------
Parse        2      0.00       0.05          0          0          0           0
Execute      2      0.00       0.01          0          0          0           0
Fetch        2      0.00       0.08        379         67          0           1
------- ------  -------- ---------- ---------- ---------- ----------  ----------
total        6      0.00       0.15        379         67          0           1

Misses in library cache during parse: 2
Misses in library cache during execute: 1

    7  user  SQL statements in session.
    2  internal SQL statements in session.
    9  SQL statements in session.
********************************************************************************
Trace file: f:\4020.trc
Trace file compatibility: 11.01.00
Sort options: fchela
       1  session in tracefile.
       7  user  SQL statements in trace file.
       2  internal SQL statements in trace file.
       9  SQL statements in trace file.
       6  unique SQL statements in trace file.
     107  lines in trace file.
     124  elapsed seconds in trace file.

 

 

來自 “ ITPUB部落格 ” ,連結:http://blog.itpub.net/22664653/viewspace-630641/,如需轉載,請註明出處,否則將追究法律責任。