--trace 原文件（该文件的顺序就是sql实际执行的顺序）

=====================
PARSING IN CURSOR #4 len=32 dep=0 uid=5 ct=3 lid=5 tim=1350528668843055 hv=2935929963 ad='36dc8d94'
--参数注释
len：被分析的sql长度
dep：产生递归sql的深度
uid：user id
otc：oracle command type 命令类型
lid：私有的用户id
tim：时间戳
hv：hash value
ad：sql address

sqlid='g4pkmrqrgxg3b'

select count(*) from dba_objects
END OF STMT
PARSE #4:c=155977,e=489729,p=10,cr=276,cu=0,mis=1,r=0,dep=0,og=1,plh=2945320129,tim=1350528668843052
--参数注释
c:消耗的cpu时间
e：elapsed time 操作用时
p：physical reads
cr：consistent reads 一致性方式读取的数据块
cu：current方式读取的数据块
mis：cursor miss in cache 硬分析的次数
r：处理的行数
dep：depth 递归sql的深度
og：optimizer goal 优化器模式
tim：时间戳

EXEC #4:c=0,e=98,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=2945320129,tim=1350528668843252

*** 2012-10-17 22:51:09.549
FETCH #4:c=513921,e=706084,p=3,cr=1774,cu=0,mis=0,r=1,dep=0,og=1,plh=2945320129,tim=1350528669549539
STAT #4 id=1 cnt=1 pid=0 pos=1 bj=0 p='SORT AGGREGATE (cr=1774 pr=3 pw=0 time=0 us)'
--参数注释
id：执行计划的行源号
cnt：当前行源返回的行数
pid：当前行源的父号
pos：执行计划中的位置
obj：当前操作对象id
op：当前行源的数据访问操作

STAT #4 id=2 cnt=72457 pid=1 pos=1 bj=3192 p='VIEW  DBA_OBJECTS (cr=1774 pr=3 pw=0 time=1925259 us cost=47 size=0 card=11457)'
STAT #4 id=3 cnt=72457 pid=2 pos=1 bj=0 p='UNION-ALL  (cr=1774 pr=3 pw=0 time=1299352 us)'
STAT #4 id=4 cnt=72457 pid=3 pos=1 bj=0 p='FILTER  (cr=1773 pr=3 pw=0 time=780531 us)'
STAT #4 id=5 cnt=73363 pid=4 pos=1 bj=0 p='HASH JOIN  (cr=907 pr=0 pw=0 time=2087110 us cost=46 size=1294012 card=12812)'
STAT #4 id=6 cnt=93 pid=5 pos=1 bj=47 p='INDEX FULL SCAN I_USER2 (cr=1 pr=0 pw=0 time=276 us cost=1 size=99 card=33)'
STAT #4 id=7 cnt=73363 pid=5 pos=2 bj=0 p='HASH JOIN  (cr=906 pr=0 pw=0 time=1369552 us cost=45 size=1255576 card=12812)'
STAT #4 id=8 cnt=93 pid=7 pos=1 bj=47 p='INDEX FULL SCAN I_USER2 (cr=1 pr=0 pw=0 time=276 us cost=1 size=693 card=33)'
STAT #4 id=9 cnt=73363 pid=7 pos=2 bj=18 p='TABLE ACCESS FULL OBJ$ (cr=905 pr=0 pw=0 time=621720 us cost=43 size=986524 card=12812)'
STAT #4 id=10 cnt=3892 pid=4 pos=2 bj=19 p='TABLE ACCESS BY INDEX ROWID IND$ (cr=866 pr=3 pw=0 time=0 us cost=2 size=8 card=1)'
STAT #4 id=11 cnt=4793 pid=10 pos=1 bj=41 p='INDEX UNIQUE SCAN I_IND1 (cr=122 pr=3 pw=0 time=0 us cost=1 size=0 card=1)'
STAT #4 id=12 cnt=0 pid=4 pos=3 bj=0 p='NESTED LOOPS  (cr=0 pr=0 pw=0 time=0 us cost=2 size=28 card=1)'
STAT #4 id=13 cnt=0 pid=12 pos=1 bj=47 p='INDEX FULL SCAN I_USER2 (cr=0 pr=0 pw=0 time=0 us cost=1 size=19 card=1)'
STAT #4 id=14 cnt=0 pid=12 pos=2 bj=39 p='INDEX RANGE SCAN I_OBJ4 (cr=0 pr=0 pw=0 time=0 us cost=1 size=9 card=1)'
STAT #4 id=15 cnt=0 pid=3 pos=2 bj=0 p='NESTED LOOPS  (cr=1 pr=0 pw=0 time=0 us cost=1 size=16 card=1)'
STAT #4 id=16 cnt=0 pid=15 pos=1 bj=137 p='INDEX FULL SCAN I_LINK1 (cr=1 pr=0 pw=0 time=0 us cost=0 size=13 card=1)'
STAT #4 id=17 cnt=0 pid=15 pos=2 bj=47 p='INDEX RANGE SCAN I_USER2 (cr=0 pr=0 pw=0 time=0 us cost=1 size=3 card=1)'
FETCH #4:c=0,e=4,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,plh=2945320129,tim=1350528669550791

*** 2012-10-17 22:51:19.314
CLOSE #4:c=0,e=42,dep=0,type=0,tim=1350528679314067
=====================

--格式化后的输出

SQL ID: g4pkmrqrgxg3b
Plan Hash: 2945320129
select count(*)
from
 dba_objects

call     count       cpu    elapsed       disk      query    current        rows
------- ------  -------- ---------- ---------- ---------- ----------  ----------
Parse        1      0.05       0.12          0          0          0           0
Execute      1      0.00       0.00          0          0          0           0
Fetch        2      0.51       0.70          3       1774          0           1
------- ------  -------- ---------- ---------- ---------- ----------  ----------
total        4      0.56       0.82          3       1774          0           1

Misses in library cache during parse: 1
Optimizer mode: ALL_ROWS
Parsing user id: 5  (SYSTEM)

Rows     Row Source Operation
-------  ---------------------------------------------------
      1  SORT AGGREGATE (cr=1774 pr=3 pw=0 time=0 us)
  72457   VIEW  DBA_OBJECTS (cr=1774 pr=3 pw=0 time=1925259 us cost=47 size=0 card=11457)
  72457    UNION-ALL  (cr=1774 pr=3 pw=0 time=1299352 us)
  72457     FILTER  (cr=1773 pr=3 pw=0 time=780531 us)
  73363      HASH JOIN  (cr=907 pr=0 pw=0 time=2087110 us cost=46 size=1294012 card=12812)
     93       INDEX FULL SCAN I_USER2 (cr=1 pr=0 pw=0 time=276 us cost=1 size=99 card=33)(object id 47)
  73363       HASH JOIN  (cr=906 pr=0 pw=0 time=1369552 us cost=45 size=1255576 card=12812)
     93        INDEX FULL SCAN I_USER2 (cr=1 pr=0 pw=0 time=276 us cost=1 size=693 card=33)(object id 47)
  73363        TABLE ACCESS FULL OBJ$ (cr=905 pr=0 pw=0 time=621720 us cost=43 size=986524 card=12812)
   3892      TABLE ACCESS BY INDEX ROWID IND$ (cr=866 pr=3 pw=0 time=0 us cost=2 size=8 card=1)
   4793       INDEX UNIQUE SCAN I_IND1 (cr=122 pr=3 pw=0 time=0 us cost=1 size=0 card=1)(object id 41)
      0      NESTED LOOPS  (cr=0 pr=0 pw=0 time=0 us cost=2 size=28 card=1)
      0       INDEX FULL SCAN I_USER2 (cr=0 pr=0 pw=0 time=0 us cost=1 size=19 card=1)(object id 47)
      0       INDEX RANGE SCAN I_OBJ4 (cr=0 pr=0 pw=0 time=0 us cost=1 size=9 card=1)(object id 39)
      0     NESTED LOOPS  (cr=1 pr=0 pw=0 time=0 us cost=1 size=16 card=1)
      0      INDEX FULL SCAN I_LINK1 (cr=1 pr=0 pw=0 time=0 us cost=0 size=13 card=1)(object id 137)
      0      INDEX RANGE SCAN I_USER2 (cr=0 pr=0 pw=0 time=0 us cost=1 size=3 card=1)(object id 47)

Rows     Execution Plan
-------  ---------------------------------------------------
      0  SELECT STATEMENT   MODE: ALL_ROWS
      1   SORT (AGGREGATE)
  72457    VIEW OF 'DBA_OBJECTS' (VIEW)
  72457     UNION-ALL
  72457      FILTER
  73363       HASH JOIN
     93        INDEX   MODE: ANALYZED (FULL SCAN) OF 'I_USER2' (INDEX
                   (UNIQUE))
  73363        HASH JOIN
     93         INDEX   MODE: ANALYZED (FULL SCAN) OF 'I_USER2'
                    (INDEX (UNIQUE))
  73363         TABLE ACCESS   MODE: ANALYZED (FULL) OF 'OBJ$'
                    (TABLE)
   3892       TABLE ACCESS   MODE: ANALYZED (BY INDEX ROWID) OF 'IND$'
                  (CLUSTER)
   4793        INDEX   MODE: ANALYZED (UNIQUE SCAN) OF 'I_IND1'
                   (INDEX (UNIQUE))
      0       NESTED LOOPS
      0        INDEX   MODE: ANALYZED (FULL SCAN) OF 'I_USER2' (INDEX
                   (UNIQUE))
      0        INDEX   MODE: ANALYZED (RANGE SCAN) OF 'I_OBJ4' (INDEX)

      0      NESTED LOOPS
      0       INDEX   MODE: ANALYZED (FULL SCAN) OF 'I_LINK1' (INDEX)
      0       INDEX   MODE: ANALYZED (RANGE SCAN) OF 'I_USER2' (INDEX
                  (UNIQUE))