10046事件

来源：互联网发布：上海银行淘宝金卡年费编辑：程序博客网时间：2024/05/16 07:14

from http://blog.itpub.net/26451536/viewspace-765684/

10046事件是大家非常熟悉的一种分析方式，分为level 1、4、8、12四个级别。
level 1等同于sql_trace，level 4增加了绑定变量信息，
level 8增加了等待事件信息，level 12增加了绑定变量和等待事件信息。

为了学习10046事件跟踪文件的内容，我进行了如下操作：

++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
首先，将shared_pool和buffer_cache都清空，再重新建立连接。以查看硬解析的各种数据。
Connected to Oracle Database 10g Release 10.2.0.1.0
Connected as scott

23:47:28 SQL>
23:47:34 SQL> set time on
23:47:34 SQL> alter session set events '10046 trace name context forever, level 12';

Session altered

23:47:34 SQL> select job,dname from dept,emp where dept.deptno=emp.deptno;

JOB       DNAME
--------- --------------
CLERK     RESEARCH
MANAGER   RESEARCH
MANAGER   ACCOUNTING
ANALYST   RESEARCH
PRESIDENT ACCOUNTING
CLERK     RESEARCH
CLERK     ACCOUNTING

7 rows selected

23:47:34 SQL> update emp set ename=ename||'a';

7 rows updated

23:47:35 SQL> delete from emp where empno in (select empno from dept where dept.dname='RESEARCH');

7 rows deleted

23:47:35 SQL> commit;

Commit complete

23:47:35 SQL> insert into EMP (EMPNO, ENAME, JOB, MGR, HIREDATE, SAL, COMM, DEPTNO)
2 values (7876, 'ADAMS', 'CLERK', 7788, to_date('23-05-1987', 'dd-mm-yyyy'), 1100, null, 20);

1 row inserted

23:47:35 SQL> commit;

Commit complete

23:47:35 SQL> alter session set events '10046 trace name context off';

Session altered

23:47:35 SQL> delete from emp where empno=7876;

1 row deleted

23:47:35 SQL> commit;

Commit complete

23:47:39 SQL>

说明：
我首先设置了10046跟踪，接着执行查询、更新、删除操作后提交事务；
再执行插入操作，提交事务；
关闭10046跟踪事件；
再次执行删除操作。
++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++

我执行的查询语句是：select job,dname from dept,emp where dept.deptno=emp.deptno

将10046跟踪产生的文件中该语句之前的select语句罗列如下：

select 'x' from dual
select obj#,type#,ctime,mtime,stime,status,dataobj#,flags,oid$, spare1, spare2 from obj$ where owner#=:1 and name=:2 and namespace=:3 and remoteowner is null and linkname is null and subname is null
select t.ts#,t.file#,t.block#,nvl(t.bobj#,0),nvl(t.tab#,0),t.intcols,nvl(t.clucols,0),t.audit$,t.flags,t.pctfree$,t.pctused$,t.initrans,t.maxtrans,t.rowcnt,t.blkcnt,t.empcnt,t.avgspc,t.chncnt,t.avgrln,t.analyzetime,t.samplesize,t.cols,t.property,nvl(t.degree,1),nvl(t.instances,1),t.avgspc_flb,t.flbcnt,t.kernelcols,nvl(t.trigflag, 0),nvl(t.spare1,0),nvl(t.spare2,0),t.spare4,t.spare6,ts.cachedblk,ts.cachehit,ts.logicalread from tab$ t, tab_stats$ ts where t.obj#= :1 and t.obj# = ts.obj# (+)
select i.obj#,i.ts#,i.file#,i.block#,i.intcols,i.type#,i.flags,i.property,i.pctfree$,i.initrans,i.maxtrans,i.blevel,i.leafcnt,i.distkey,i.lblkkey,i.dblkkey,i.clufac,i.cols,i.analyzetime,i.samplesize,i.dataobj#,nvl(i.degree,1),nvl(i.instances,1),i.rowcnt,mod(i.pctthres$,256),i.indmethod#,i.trunccnt,nvl(c.unicols,0),nvl(c.deferrable#+c.valid#,0),nvl(i.spare1,i.intcols),i.spare4,i.spare2,i.spare6,decode(i.pctthres$,null,null,mod(trunc(i.pctthres$/256),256)),ist.cachedblk,ist.cachehit,ist.logicalread from ind$ i, ind_stats$ ist, (select enabled, min(cols) unicols,min(to_number(bitand(defer,1))) deferrable#,min(to_number(bitand(defer,4))) valid# from cdef$ where obj#=:1 and enabled > 1 group by enabled) c where i.obj#=c.enabled(+) and i.obj# = ist.obj#(+) and i.bo#=:1 order by i.obj#
select pos#,intcol#,col#,spare1,bo#,spare2 from icol$ where obj#=:1
select pos#,intcol#,col#,spare1,bo#,spare2 from icol$ where obj#=:1
select name,intcol#,segcol#,type#,length,nvl(precision#,0),decode(type#,2,nvl(scale,-127/*MAXSB1MINAL*/),178,scale,179,scale,180,scale,181,scale,182,scale,183,scale,231,scale,0),null$,fixedstorage,nvl(deflength,0),default$,rowid,col#,property, nvl(charsetid,0),nvl(charsetform,0),spare1,spare2,nvl(spare3,0) from col$ where obj#=:1 order by intcol#
select type#,blocks,extents,minexts,maxexts,extsize,extpct,user#,iniexts,NVL(lists,65535),NVL(groups,65535),cachehint,hwmincr, NVL(spare1,0),NVL(scanhint,0) from seg$ where ts#=:1 and file#=:2 and block#=:3
select o.owner#,o.name,o.namespace,o.remoteowner,o.linkname,o.subname,o.dataobj#,o.flags from obj$ o where o.obj#=:1
select type#,blocks,extents,minexts,maxexts,extsize,extpct,user#,iniexts,NVL(lists,65535),NVL(groups,65535),cachehint,hwmincr, NVL(spare1,0),NVL(scanhint,0) from seg$ where ts#=:1 and file#=:2 and block#=:3
select o.owner#,o.name,o.namespace,o.remoteowner,o.linkname,o.subname,o.dataobj#,o.flags from obj$ o where o.obj#=:1
select type#,blocks,extents,minexts,maxexts,extsize,extpct,user#,iniexts,NVL(lists,65535),NVL(groups,65535),cachehint,hwmincr, NVL(spare1,0),NVL(scanhint,0) from seg$ where ts#=:1 and file#=:2 and block#=:3

select obj#,type#,ctime,mtime,stime,status,dataobj#,flags,oid$, spare1, spare2 from obj$ where owner#=:1 and name=:2 and namespace=:3 and remoteowner is null and linkname is null and subname is null
select t.ts#,t.file#,t.block#,nvl(t.bobj#,0),nvl(t.tab#,0),t.intcols,nvl(t.clucols,0),t.audit$,t.flags,t.pctfree$,t.pctused$,t.initrans,t.maxtrans,t.rowcnt,t.blkcnt,t.empcnt,t.avgspc,t.chncnt,t.avgrln,t.analyzetime,t.samplesize,t.cols,t.property,nvl(t.degree,1),nvl(t.instances,1),t.avgspc_flb,t.flbcnt,t.kernelcols,nvl(t.trigflag, 0),nvl(t.spare1,0),nvl(t.spare2,0),t.spare4,t.spare6,ts.cachedblk,ts.cachehit,ts.logicalread from tab$ t, tab_stats$ ts where t.obj#= :1 and t.obj# = ts.obj# (+)
select i.obj#,i.ts#,i.file#,i.block#,i.intcols,i.type#,i.flags,i.property,i.pctfree$,i.initrans,i.maxtrans,i.blevel,i.leafcnt,i.distkey,i.lblkkey,i.dblkkey,i.clufac,i.cols,i.analyzetime,i.samplesize,i.dataobj#,nvl(i.degree,1),nvl(i.instances,1),i.rowcnt,mod(i.pctthres$,256),i.indmethod#,i.trunccnt,nvl(c.unicols,0),nvl(c.deferrable#+c.valid#,0),nvl(i.spare1,i.intcols),i.spare4,i.spare2,i.spare6,decode(i.pctthres$,null,null,mod(trunc(i.pctthres$/256),256)),ist.cachedblk,ist.cachehit,ist.logicalread from ind$ i, ind_stats$ ist, (select enabled, min(cols) unicols,min(to_number(bitand(defer,1))) deferrable#,min(to_number(bitand(defer,4))) valid# from cdef$ where obj#=:1 and enabled > 1 group by enabled) c where i.obj#=c.enabled(+) and i.obj# = ist.obj#(+) and i.bo#=:1 order by i.obj#
select pos#,intcol#,col#,spare1,bo#,spare2 from icol$ where obj#=:1
select name,intcol#,segcol#,type#,length,nvl(precision#,0),decode(type#,2,nvl(scale,-127/*MAXSB1MINAL*/),178,scale,179,scale,180,scale,181,scale,182,scale,183,scale,231,scale,0),null$,fixedstorage,nvl(deflength,0),default$,rowid,col#,property, nvl(charsetid,0),nvl(charsetform,0),spare1,spare2,nvl(spare3,0) from col$ where obj#=:1 order by intcol#
select type#,blocks,extents,minexts,maxexts,extsize,extpct,user#,iniexts,NVL(lists,65535),NVL(groups,65535),cachehint,hwmincr, NVL(spare1,0),NVL(scanhint,0) from seg$ where ts#=:1 and file#=:2 and block#=:3
select o.owner#,o.name,o.namespace,o.remoteowner,o.linkname,o.subname,o.dataobj#,o.flags from obj$ o where o.obj#=:1
select type#,blocks,extents,minexts,maxexts,extsize,extpct,user#,iniexts,NVL(lists,65535),NVL(groups,65535),cachehint,hwmincr, NVL(spare1,0),NVL(scanhint,0) from seg$ where ts#=:1 and file#=:2 and block#=:3

select col#, grantee#, privilege#,max(mod(nvl(option$,0),2)) from objauth$ where obj#=:1 and col# is not null group by privilege#, col#, grantee# order by col#, grantee#
select grantee#,privilege#,nvl(col#,0),max(mod(nvl(option$,0),2))from objauth$ where obj#=:1 group by grantee#,privilege#,nvl(col#,0) order by grantee#
select con#,obj#,rcon#,enabled,nvl(defer,0) from cdef$ where robj#=:1
select con#,type#,condlength,intcols,robj#,rcon#,match#,refact,nvl(enabled,0),rowid,cols,nvl(defer,0),mtime,nvl(spare1,0) from cdef$ where obj#=:1
select intcol#,nvl(pos#,0),col#,nvl(spare1,0) from ccol$ where con#=:1
select intcol#,nvl(pos#,0),col#,nvl(spare1,0) from ccol$ where con#=:1

select /*+ rule */ bucket_cnt, row_cnt, cache_cnt, null_cnt, timestamp#, sample_size, minimum, maximum, distcnt, lowval, hival, density, col#, spare1, spare2, avgcln from hist_head$ where obj#=:1 and intcol#=:2
select /*+ rule */ bucket_cnt, row_cnt, cache_cnt, null_cnt, timestamp#, sample_size, minimum, maximum, distcnt, lowval, hival, density, col#, spare1, spare2, avgcln from hist_head$ where obj#=:1 and intcol#=:2
select /*+ rule */ bucket, endpoint, col#, epvalue from histgrm$ where obj#=:1 and intcol#=:2 and row#=:3 order by bucket

select job,dname from dept,emp where dept.deptno=emp.deptno

我对语句进行了简单的分组，用空行隔开。
执行该select的过程大概为：

首先根据输入语句以及默认添加的用户名、命名空间、是否为远程对象等信息从obj$表中查到该对象的类型、等信息
然后根据object_id将tab$和tab_stats$关联得到更详细的状态信息
接着从ind$、ind_stats$关联cdef$，获取索引相关信息
依次从icol$、col$获取列的信息
从objauth$获取权限信息
hist_head$、histgrm$是内存中的历史信息，如果在此操作之前执行过相同语句，
且上次执行到现在的时间内表未发生变化、内存中的数据也没有被age out出去，oracle优先选择内存中的数据进行查询。

++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
以下是跟踪文件的内容，以‘--’开头的是我的注释信息

--在文件开头包含了文件名、日期、数据库版本、操作系统版本和CPU、内存、实例名。
Dump file d:\admin\test\udump\test_ora_6488.trc
Sat Jul 06 23:47:34 2013
ORACLE V10.2.0.1.0 - Production vsnsta=0
vsnsql=14 vsnxtr=3
Oracle Database 10g Release 10.2.0.1.0 - Production
Windows XP Version V5.1 Service Pack 2
CPU : 2 - type 586
Process Affinity : 0x00000000
Memory (Avail/Total): Ph:453M/2047M, Ph+PgF:1884M/3939M, VA:1210M/2047M
Instance name: test

Redo thread mounted by this instance: 1

Oracle process number: 32

Windows thread id: 6488, image: ORACLE.EXE (SHAD)

*** ACTION NAME:(Command Window - New) 2013-07-06 23:47:34.765
*** MODULE NAME:(PL/SQL Developer) 2013-07-06 23:47:34.765
*** SERVICE NAME:(test) 2013-07-06 23:47:34.765
*** SESSION ID:(128.125) 2013-07-06 23:47:34.765

--以下是用户操作和系统后台操作行为的痕迹（trace）
--每步骤操作包括了游标的解析、执行操作情况，执行计划:
=====================
PARSING IN CURSOR #5 len=71 dep=0 uid=54 ct=42 lid=54 tim=8736310989 hv=3051829684 ad='2f612e8c'
alter session set events '10046 trace name context forever, level 12'
END OF STMT
EXEC #5:c=0,e=79,p=0,cr=0,cu=0,mis=1,r=0,dep=0,og=1,tim=8736310983
WAIT #5: nam='SQL*Net message to client' ela= 5 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736313538
WAIT #5: nam='SQL*Net message from client' ela= 12113 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736325760
=====================
--我注意到，每个操作之间都会被系统加上两个操作：
--select 'x' from dual
--以及
--begin :id := sys.dbms_transaction.local_transaction_id; end;
--猜想这应该是为了在需要回滚或者回复数据库时隔断相邻操作的。
PARSING IN CURSOR #3 len=21 dep=0 uid=54 ct=3 lid=54 tim=8736326884 hv=2564406738 ad='2f614370'
select 'x' from dual
END OF STMT
PARSE #3:c=0,e=647,p=0,cr=0,cu=0,mis=1,r=0,dep=0,og=1,tim=8736326879
BINDS #3:
EXEC #3:c=0,e=122,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736327515
WAIT #3: nam='SQL*Net message to client' ela= 5 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736327634
WAIT #3: nam='SQL*Net message from client' ela= 273 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736328055
WAIT #3: nam='SQL*Net message to client' ela= 4 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736328290
FETCH #3:c=0,e=175,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=8736328435
WAIT #3: nam='SQL*Net message from client' ela= 8217 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736336855
STAT #3 id=1 cnt=1 pid=0 pos=1 bj=0 p='FAST DUAL (cr=0 pr=0 pw=0 time=13 us)'
=====================
PARSING IN CURSOR #6 len=61 dep=0 uid=54 ct=47 lid=54 tim=8736337441 hv=356401299 ad='2f727528'
begin :id := sys.dbms_transaction.local_transaction_id; end;
END OF STMT
PARSE #6:c=0,e=39,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736337436
BINDS #6:
kkscoacd
Bind#0
acdty=01 mxl=2000(2000) mxlc=00 mal=00 scl=00 pre=00
acflg=01 fl2=1000000 frm=01 csi=852 siz=2000 ff=0
kxsbbbfp=0d70ab88 bln=2000 avl=00 flg=05
WAIT #6: nam='SQL*Net message to client' ela= 6 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736338719
EXEC #6:c=0,e=896,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=8736338916
WAIT #6: nam='SQL*Net message from client' ela= 380 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736339538
=====================
--硬解析时首先要从数据字典表中查找相关对象信息
--（1）从obj$中根据对象的模式名、表空间和表名查询信息
PARSING IN CURSOR #5 len=198 dep=1 uid=0 ct=3 lid=0 tim=8736342580 hv=4125641360 ad='342f6388'
select obj#,type#,ctime,mtime,stime,status,dataobj#,flags,oid$, spare1, spare2 from obj$ where owner#=:1 and name=:2 and namespace=:3 and remoteowner is null and linkname is null and subname is null
END OF STMT
PARSE #5:c=15625,e=1191,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736342575
BINDS #5:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d70b494 bln=22 avl=02 flg=05
value=54
Bind#1
acdty=01 mxl=32(03) mxlc=00 mal=00 scl=00 pre=00
acflg=18 fl2=0001 frm=01 csi=852 siz=32 ff=0
kxsbbbfp=0d70b468 bln=32 avl=03 flg=05
value="EMP"
Bind#2
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d70b444 bln=24 avl=02 flg=05
value=1
--通过select语句以及上面三个绑定变量的value，其实可以复原出oracle用哪些值执行查询。
EXEC #5:c=0,e=4082,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736347535
FETCH #5:c=0,e=57,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736347709
--在STAT开头的记录中记录了详细的执行计划数据
STAT #5 id=1 cnt=1 pid=0 pos=1 bj=18 p='TABLE ACCESS BY INDEX ROWID OBJ$ (cr=3 pr=0 pw=0 time=58 us)'
STAT #5 id=2 cnt=1 pid=1 pos=1 bj=37 p='INDEX RANGE SCAN I_OBJ2 (cr=2 pr=0 pw=0 time=31 us)'
=====================
PARSING IN CURSOR #3 len=493 dep=1 uid=0 ct=3 lid=0 tim=8736349035 hv=2584065658 ad='342e4b58'
select t.ts#,t.file#,t.block#,nvl(t.bobj#,0),nvl(t.tab#,0),t.intcols,nvl(t.clucols,0),t.audit$,t.flags,t.pctfree$,t.pctused$,t.initrans,t.maxtrans,t.rowcnt,t.blkcnt,t.empcnt,t.avgspc,t.chncnt,t.avgrln,t.analyzetime,t.samplesize,t.cols,t.property,nvl(t.degree,1),nvl(t.instances,1),t.avgspc_flb,t.flbcnt,t.kernelcols,nvl(t.trigflag, 0),nvl(t.spare1,0),nvl(t.spare2,0),t.spare4,t.spare6,ts.cachedblk,ts.cachehit,ts.logicalread from tab$ t, tab_stats$ ts where t.obj#= :1 and t.obj# = ts.obj# (+)
END OF STMT
PARSE #3:c=0,e=874,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736349030
BINDS #3:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6fa2d4 bln=22 avl=04 flg=05
value=51151
EXEC #3:c=0,e=4468,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736354116
FETCH #3:c=0,e=104,p=0,cr=4,cu=0,mis=0,r=1,dep=1,og=4,tim=8736354352
STAT #3 id=1 cnt=1 pid=0 pos=1 bj=0 p='NESTED LOOPS OUTER (cr=4 pr=0 pw=0 time=109 us)'
STAT #3 id=2 cnt=1 pid=1 pos=1 bj=4 p='TABLE ACCESS CLUSTER TAB$ (cr=3 pr=0 pw=0 time=64 us)'
STAT #3 id=3 cnt=1 pid=2 pos=1 bj=3 p='INDEX UNIQUE SCAN I_OBJ# (cr=2 pr=0 pw=0 time=27 us)'
STAT #3 id=4 cnt=0 pid=1 pos=2 bj=708 p='TABLE ACCESS BY INDEX ROWID TAB_STATS$ (cr=1 pr=0 pw=0 time=24 us)'
STAT #3 id=5 cnt=0 pid=4 pos=1 bj=709 p='INDEX UNIQUE SCAN I_TAB_STATS$_OBJ# (cr=1 pr=0 pw=0 time=11 us)'
=====================
PARSING IN CURSOR #6 len=789 dep=1 uid=0 ct=3 lid=0 tim=8736356270 hv=3159716790 ad='342e2838'
select i.obj#,i.ts#,i.file#,i.block#,i.intcols,i.type#,i.flags,i.property,i.pctfree$,i.initrans,i.maxtrans,i.blevel,i.leafcnt,i.distkey,i.lblkkey,i.dblkkey,i.clufac,i.cols,i.analyzetime,i.samplesize,i.dataobj#,nvl(i.degree,1),nvl(i.instances,1),i.rowcnt,mod(i.pctthres$,256),i.indmethod#,i.trunccnt,nvl(c.unicols,0),nvl(c.deferrable#+c.valid#,0),nvl(i.spare1,i.intcols),i.spare4,i.spare2,i.spare6,decode(i.pctthres$,null,null,mod(trunc(i.pctthres$/256),256)),ist.cachedblk,ist.cachehit,ist.logicalread from ind$ i, ind_stats$ ist, (select enabled, min(cols) unicols,min(to_number(bitand(defer,1))) deferrable#,min(to_number(bitand(defer,4))) valid# from cdef$ where obj#=:1 and enabled > 1 group by enabled) c where i.obj#=c.enabled(+) and i.obj# = ist.obj#(+) and i.bo#=:1 order by i.obj#
END OF STMT
PARSE #6:c=0,e=1235,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736356265
BINDS #6:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6e860c bln=22 avl=04 flg=05
value=51151
Bind#1
No oacdef for this bind.
EXEC #6:c=15625,e=6206,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736363069
WAIT #6: nam='db file sequential read' ela= 2790 file#=1 block#=52525 blocks=1 obj#=-1 tim=8736368399
WAIT #6: nam='db file sequential read' ela= 11139 file#=1 block#=59441 blocks=1 obj#=-1 tim=8736379847
FETCH #6:c=0,e=18522,p=2,cr=10,cu=0,mis=0,r=1,dep=1,og=4,tim=8736381766
=====================
PARSING IN CURSOR #5 len=67 dep=1 uid=0 ct=3 lid=0 tim=8736382578 hv=2280069326 ad='342f51e8'
select pos#,intcol#,col#,spare1,bo#,spare2 from icol$ where obj#=:1
END OF STMT
PARSE #5:c=0,e=516,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736382574
BINDS #5:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0da0da10 bln=22 avl=04 flg=05
value=51152
EXEC #5:c=0,e=3102,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736386363
FETCH #5:c=0,e=52,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736386606
FETCH #5:c=0,e=19,p=0,cr=1,cu=0,mis=0,r=0,dep=1,og=4,tim=8736386774
STAT #5 id=1 cnt=1 pid=0 pos=1 bj=20 p='TABLE ACCESS BY INDEX ROWID ICOL$ (cr=4 pr=0 pw=0 time=71 us)'
STAT #5 id=2 cnt=1 pid=1 pos=1 bj=40 p='INDEX RANGE SCAN I_ICOL1 (cr=3 pr=0 pw=0 time=41 us)'
FETCH #6:c=0,e=26,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=4,tim=8736387438
BINDS #3:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0da0da10 bln=22 avl=04 flg=05
value=52284
=====================
PARSING IN CURSOR #3 len=67 dep=1 uid=0 ct=3 lid=0 tim=8736387969 hv=2280069326 ad='342f51e8'
select pos#,intcol#,col#,spare1,bo#,spare2 from icol$ where obj#=:1
END OF STMT
EXEC #3:c=0,e=178,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736387965
WAIT #3: nam='db file sequential read' ela= 4445 file#=1 block#=56906 blocks=1 obj#=-1 tim=8736392503
FETCH #3:c=0,e=4562,p=1,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736392585
FETCH #3:c=0,e=16,p=0,cr=1,cu=0,mis=0,r=0,dep=1,og=4,tim=8736392651
FETCH #6:c=0,e=16,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736392718
STAT #6 id=1 cnt=2 pid=0 pos=1 bj=0 p='SORT ORDER BY (cr=10 pr=2 pw=0 time=18560 us)'
STAT #6 id=2 cnt=2 pid=1 pos=1 bj=0 p='HASH JOIN OUTER (cr=10 pr=2 pw=0 time=17051 us)'
STAT #6 id=3 cnt=2 pid=2 pos=1 bj=0 p='NESTED LOOPS OUTER (cr=7 pr=0 pw=0 time=159 us)'
STAT #6 id=4 cnt=2 pid=3 pos=1 bj=19 p='TABLE ACCESS CLUSTER IND$ (cr=5 pr=0 pw=0 time=78 us)'
STAT #6 id=5 cnt=1 pid=4 pos=1 bj=3 p='INDEX UNIQUE SCAN I_OBJ# (cr=2 pr=0 pw=0 time=28 us)'
STAT #6 id=6 cnt=0 pid=3 pos=2 bj=710 p='TABLE ACCESS BY INDEX ROWID IND_STATS$ (cr=2 pr=0 pw=0 time=54 us)'
STAT #6 id=7 cnt=0 pid=6 pos=1 bj=711 p='INDEX UNIQUE SCAN I_IND_STATS$_OBJ# (cr=2 pr=0 pw=0 time=25 us)'
STAT #6 id=8 cnt=1 pid=2 pos=2 bj=0 p='VIEW (cr=3 pr=2 pw=0 time=14749 us)'
STAT #6 id=9 cnt=1 pid=8 pos=1 bj=0 p='SORT GROUP BY (cr=3 pr=2 pw=0 time=14730 us)'
STAT #6 id=10 cnt=1 pid=9 pos=1 bj=31 p='TABLE ACCESS BY INDEX ROWID CDEF$ (cr=3 pr=2 pw=0 time=14617 us)'
STAT #6 id=11 cnt=2 pid=10 pos=1 bj=51 p='INDEX RANGE SCAN I_CDEF2 (cr=2 pr=1 pw=0 time=3126 us)'
=====================
PARSING IN CURSOR #5 len=348 dep=1 uid=0 ct=3 lid=0 tim=8736393597 hv=2512561537 ad='342f4bf8'
select name,intcol#,segcol#,type#,length,nvl(precision#,0),decode(type#,2,nvl(scale,-127/*MAXSB1MINAL*/),178,scale,179,scale,180,scale,181,scale,182,scale,183,scale,231,scale,0),null$,fixedstorage,nvl(deflength,0),default$,rowid,col#,property, nvl(charsetid,0),nvl(charsetform,0),spare1,spare2,nvl(spare3,0) from col$ where obj#=:1 order by intcol#
END OF STMT
PARSE #5:c=0,e=664,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736393592
BINDS #5:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0da0f9f8 bln=22 avl=04 flg=05
value=51151
EXEC #5:c=0,e=2662,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736396381
FETCH #5:c=0,e=100,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736396532
FETCH #5:c=0,e=7,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=4,tim=8736396570
FETCH #5:c=0,e=7,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=4,tim=8736396599
FETCH #5:c=0,e=6,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=4,tim=8736396627
FETCH #5:c=0,e=6,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=4,tim=8736396655
FETCH #5:c=0,e=7,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=4,tim=8736396683
FETCH #5:c=0,e=6,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=4,tim=8736396710
FETCH #5:c=0,e=7,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=4,tim=8736396738
FETCH #5:c=0,e=10,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736396770
STAT #5 id=1 cnt=8 pid=0 pos=1 bj=0 p='SORT ORDER BY (cr=3 pr=0 pw=0 time=113 us)'
STAT #5 id=2 cnt=8 pid=1 pos=1 bj=21 p='TABLE ACCESS CLUSTER COL$ (cr=3 pr=0 pw=0 time=78 us)'
STAT #5 id=3 cnt=1 pid=2 pos=1 bj=3 p='INDEX UNIQUE SCAN I_OBJ# (cr=2 pr=0 pw=0 time=22 us)'
=====================
PARSING IN CURSOR #3 len=206 dep=1 uid=0 ct=3 lid=0 tim=8736398863 hv=2888850659 ad='342e1ff0'
select type#,blocks,extents,minexts,maxexts,extsize,extpct,user#,iniexts,NVL(lists,65535),NVL(groups,65535),cachehint,hwmincr, NVL(spare1,0),NVL(scanhint,0) from seg$ where ts#=:1 and file#=:2 and block#=:3
END OF STMT
PARSE #3:c=0,e=1939,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736398858
BINDS #3:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0da0e534 bln=22 avl=02 flg=05
value=4
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0da0e510 bln=24 avl=02 flg=05
value=4
Bind#2
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0da0e4ec bln=24 avl=02 flg=05
value=27
EXEC #3:c=0,e=1617,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736400607
WAIT #3: nam='db file sequential read' ela= 321 file#=1 block#=59434 blocks=1 obj#=-1 tim=8736401023
WAIT #3: nam='db file sequential read' ela= 553 file#=1 block#=56919 blocks=1 obj#=-1 tim=8736401656
FETCH #3:c=0,e=1079,p=2,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736401738
STAT #3 id=1 cnt=1 pid=0 pos=1 bj=14 p='TABLE ACCESS CLUSTER SEG$ (cr=3 pr=2 pw=0 time=1074 us)'
STAT #3 id=2 cnt=1 pid=1 pos=1 bj=9 p='INDEX UNIQUE SCAN I_FILE#_BLOCK# (cr=2 pr=1 pw=0 time=416 us)'
=====================
PARSING IN CURSOR #6 len=116 dep=1 uid=0 ct=3 lid=0 tim=8736403238 hv=854877822 ad='342f461c'
select o.owner#,o.name,o.namespace,o.remoteowner,o.linkname,o.subname,o.dataobj#,o.flags from obj$ o where o.obj#=:1
END OF STMT
PARSE #6:c=0,e=1330,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736403234
BINDS #6:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0da0c1e0 bln=22 avl=04 flg=05
value=52284
EXEC #6:c=15625,e=1179,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736404525
FETCH #6:c=0,e=52,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736404625
STAT #6 id=1 cnt=1 pid=0 pos=1 bj=18 p='TABLE ACCESS BY INDEX ROWID OBJ$ (cr=3 pr=0 pw=0 time=52 us)'
STAT #6 id=2 cnt=1 pid=1 pos=1 bj=36 p='INDEX UNIQUE SCAN I_OBJ1 (cr=2 pr=0 pw=0 time=24 us)'
=====================
PARSING IN CURSOR #5 len=206 dep=1 uid=0 ct=3 lid=0 tim=8736404824 hv=2888850659 ad='342e1ff0'
select type#,blocks,extents,minexts,maxexts,extsize,extpct,user#,iniexts,NVL(lists,65535),NVL(groups,65535),cachehint,hwmincr, NVL(spare1,0),NVL(scanhint,0) from seg$ where ts#=:1 and file#=:2 and block#=:3
END OF STMT
PARSE #5:c=0,e=52,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736404819
BINDS #5:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f850c bln=22 avl=02 flg=05
value=4
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f84e8 bln=24 avl=02 flg=05
value=4
Bind#2
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f84c4 bln=24 avl=03 flg=05
value=2643
EXEC #5:c=0,e=185,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736406248
WAIT #5: nam='db file sequential read' ela= 9531 file#=1 block#=60593 blocks=1 obj#=-1 tim=8736415852
FETCH #5:c=0,e=9651,p=1,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736415927
STAT #5 id=1 cnt=1 pid=0 pos=1 bj=14 p='TABLE ACCESS CLUSTER SEG$ (cr=3 pr=1 pw=0 time=9647 us)'
STAT #5 id=2 cnt=1 pid=1 pos=1 bj=9 p='INDEX UNIQUE SCAN I_FILE#_BLOCK# (cr=2 pr=0 pw=0 time=21 us)'
BINDS #3:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0da0c1e0 bln=22 avl=04 flg=05
value=51152
=====================
PARSING IN CURSOR #3 len=116 dep=1 uid=0 ct=3 lid=0 tim=8736416190 hv=854877822 ad='342f461c'
select o.owner#,o.name,o.namespace,o.remoteowner,o.linkname,o.subname,o.dataobj#,o.flags from obj$ o where o.obj#=:1
END OF STMT
EXEC #3:c=0,e=100,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736416186
FETCH #3:c=0,e=42,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736416284
=====================
PARSING IN CURSOR #6 len=206 dep=1 uid=0 ct=3 lid=0 tim=8736416376 hv=2888850659 ad='342e1ff0'
select type#,blocks,extents,minexts,maxexts,extsize,extpct,user#,iniexts,NVL(lists,65535),NVL(groups,65535),cachehint,hwmincr, NVL(spare1,0),NVL(scanhint,0) from seg$ where ts#=:1 and file#=:2 and block#=:3
END OF STMT
PARSE #6:c=0,e=34,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736416372
BINDS #6:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f850c bln=22 avl=02 flg=05
value=4
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f84e8 bln=24 avl=02 flg=05
value=4
Bind#2
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f84c4 bln=24 avl=02 flg=05
value=35
EXEC #6:c=0,e=150,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736416693
FETCH #6:c=0,e=51,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736416773
STAT #6 id=1 cnt=1 pid=0 pos=1 bj=14 p='TABLE ACCESS CLUSTER SEG$ (cr=3 pr=0 pw=0 time=51 us)'
STAT #6 id=2 cnt=1 pid=1 pos=1 bj=9 p='INDEX UNIQUE SCAN I_FILE#_BLOCK# (cr=2 pr=0 pw=0 time=21 us)'
BINDS #5:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d70b494 bln=22 avl=02 flg=05
value=54
Bind#1
acdty=01 mxl=32(04) mxlc=00 mal=00 scl=00 pre=00
acflg=18 fl2=0001 frm=01 csi=852 siz=32 ff=0
kxsbbbfp=0d70b468 bln=32 avl=04 flg=05
value="DEPT"
Bind#2
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d70b444 bln=24 avl=02 flg=05
value=1
=====================
PARSING IN CURSOR #5 len=198 dep=1 uid=0 ct=3 lid=0 tim=8736417089 hv=4125641360 ad='342f6388'
select obj#,type#,ctime,mtime,stime,status,dataobj#,flags,oid$, spare1, spare2 from obj$ where owner#=:1 and name=:2 and namespace=:3 and remoteowner is null and linkname is null and subname is null
END OF STMT
EXEC #5:c=0,e=148,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736417085
FETCH #5:c=0,e=48,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736417187
STAT #5 id=1 cnt=2 pid=0 pos=1 bj=18 p='TABLE ACCESS BY INDEX ROWID OBJ$ (cr=6 pr=0 pw=0 time=108 us)'
STAT #5 id=2 cnt=2 pid=1 pos=1 bj=37 p='INDEX RANGE SCAN I_OBJ2 (cr=4 pr=0 pw=0 time=56 us)'
BINDS #3:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6fa2d4 bln=22 avl=04 flg=05
value=51149
=====================
PARSING IN CURSOR #3 len=493 dep=1 uid=0 ct=3 lid=0 tim=8736417444 hv=2584065658 ad='342e4b58'
select t.ts#,t.file#,t.block#,nvl(t.bobj#,0),nvl(t.tab#,0),t.intcols,nvl(t.clucols,0),t.audit$,t.flags,t.pctfree$,t.pctused$,t.initrans,t.maxtrans,t.rowcnt,t.blkcnt,t.empcnt,t.avgspc,t.chncnt,t.avgrln,t.analyzetime,t.samplesize,t.cols,t.property,nvl(t.degree,1),nvl(t.instances,1),t.avgspc_flb,t.flbcnt,t.kernelcols,nvl(t.trigflag, 0),nvl(t.spare1,0),nvl(t.spare2,0),t.spare4,t.spare6,ts.cachedblk,ts.cachehit,ts.logicalread from tab$ t, tab_stats$ ts where t.obj#= :1 and t.obj# = ts.obj# (+)
END OF STMT
EXEC #3:c=0,e=103,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736417439
FETCH #3:c=0,e=84,p=0,cr=4,cu=0,mis=0,r=1,dep=1,og=4,tim=8736417582
STAT #3 id=1 cnt=2 pid=0 pos=1 bj=0 p='NESTED LOOPS OUTER (cr=8 pr=0 pw=0 time=196 us)'
STAT #3 id=2 cnt=2 pid=1 pos=1 bj=4 p='TABLE ACCESS CLUSTER TAB$ (cr=6 pr=0 pw=0 time=103 us)'
STAT #3 id=3 cnt=2 pid=2 pos=1 bj=3 p='INDEX UNIQUE SCAN I_OBJ# (cr=4 pr=0 pw=0 time=43 us)'
STAT #3 id=4 cnt=0 pid=1 pos=2 bj=708 p='TABLE ACCESS BY INDEX ROWID TAB_STATS$ (cr=2 pr=0 pw=0 time=49 us)'
STAT #3 id=5 cnt=0 pid=4 pos=1 bj=709 p='INDEX UNIQUE SCAN I_TAB_STATS$_OBJ# (cr=2 pr=0 pw=0 time=22 us)'
BINDS #6:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6e860c bln=22 avl=04 flg=05
value=51149
Bind#1
No oacdef for this bind.
=====================
PARSING IN CURSOR #6 len=789 dep=1 uid=0 ct=3 lid=0 tim=8736418096 hv=3159716790 ad='342e2838'
select i.obj#,i.ts#,i.file#,i.block#,i.intcols,i.type#,i.flags,i.property,i.pctfree$,i.initrans,i.maxtrans,i.blevel,i.leafcnt,i.distkey,i.lblkkey,i.dblkkey,i.clufac,i.cols,i.analyzetime,i.samplesize,i.dataobj#,nvl(i.degree,1),nvl(i.instances,1),i.rowcnt,mod(i.pctthres$,256),i.indmethod#,i.trunccnt,nvl(c.unicols,0),nvl(c.deferrable#+c.valid#,0),nvl(i.spare1,i.intcols),i.spare4,i.spare2,i.spare6,decode(i.pctthres$,null,null,mod(trunc(i.pctthres$/256),256)),ist.cachedblk,ist.cachehit,ist.logicalread from ind$ i, ind_stats$ ist, (select enabled, min(cols) unicols,min(to_number(bitand(defer,1))) deferrable#,min(to_number(bitand(defer,4))) valid# from cdef$ where obj#=:1 and enabled > 1 group by enabled) c where i.obj#=c.enabled(+) and i.obj# = ist.obj#(+) and i.bo#=:1 order by i.obj#
END OF STMT
EXEC #6:c=0,e=164,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736418091
FETCH #6:c=15625,e=1897,p=0,cr=8,cu=0,mis=0,r=1,dep=1,og=4,tim=8736420056
BINDS #5:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0da0da10 bln=22 avl=04 flg=05
value=51150
=====================
PARSING IN CURSOR #5 len=67 dep=1 uid=0 ct=3 lid=0 tim=8736420698 hv=2280069326 ad='342f51e8'
select pos#,intcol#,col#,spare1,bo#,spare2 from icol$ where obj#=:1
END OF STMT
EXEC #5:c=0,e=536,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736420692
FETCH #5:c=0,e=48,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736420814
FETCH #5:c=0,e=14,p=0,cr=1,cu=0,mis=0,r=0,dep=1,og=4,tim=8736420854
FETCH #6:c=0,e=12,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736420912
BINDS #3:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0da0f9f8 bln=22 avl=04 flg=05
value=51149
=====================
PARSING IN CURSOR #3 len=348 dep=1 uid=0 ct=3 lid=0 tim=8736421126 hv=2512561537 ad='342f4bf8'
select name,intcol#,segcol#,type#,length,nvl(precision#,0),decode(type#,2,nvl(scale,-127/*MAXSB1MINAL*/),178,scale,179,scale,180,scale,181,scale,182,scale,183,scale,231,scale,0),null$,fixedstorage,nvl(deflength,0),default$,rowid,col#,property, nvl(charsetid,0),nvl(charsetform,0),spare1,spare2,nvl(spare3,0) from col$ where obj#=:1 order by intcol#
END OF STMT
EXEC #3:c=0,e=119,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736421121
FETCH #3:c=0,e=88,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736421271
FETCH #3:c=0,e=7,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=4,tim=8736421308
FETCH #3:c=0,e=5,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=4,tim=8736421335
FETCH #3:c=0,e=8,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736421365
=====================
PARSING IN CURSOR #5 len=206 dep=1 uid=0 ct=3 lid=0 tim=8736421506 hv=2888850659 ad='342e1ff0'
select type#,blocks,extents,minexts,maxexts,extsize,extpct,user#,iniexts,NVL(lists,65535),NVL(groups,65535),cachehint,hwmincr, NVL(spare1,0),NVL(scanhint,0) from seg$ where ts#=:1 and file#=:2 and block#=:3
END OF STMT
PARSE #5:c=0,e=57,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736421502
BINDS #5:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f850c bln=22 avl=02 flg=05
value=4
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f84e8 bln=24 avl=02 flg=05
value=4
Bind#2
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f84c4 bln=24 avl=02 flg=05
value=11
EXEC #5:c=0,e=160,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736421766
FETCH #5:c=0,e=47,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736421843
STAT #5 id=1 cnt=1 pid=0 pos=1 bj=14 p='TABLE ACCESS CLUSTER SEG$ (cr=3 pr=0 pw=0 time=49 us)'
STAT #5 id=2 cnt=1 pid=1 pos=1 bj=9 p='INDEX UNIQUE SCAN I_FILE#_BLOCK# (cr=2 pr=0 pw=0 time=21 us)'
BINDS #6:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0da0c1e0 bln=22 avl=04 flg=05
value=51150
=====================
PARSING IN CURSOR #6 len=116 dep=1 uid=0 ct=3 lid=0 tim=8736422063 hv=854877822 ad='342f461c'
select o.owner#,o.name,o.namespace,o.remoteowner,o.linkname,o.subname,o.dataobj#,o.flags from obj$ o where o.obj#=:1
END OF STMT
EXEC #6:c=0,e=90,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736422059
FETCH #6:c=0,e=46,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736422252
=====================
PARSING IN CURSOR #3 len=206 dep=1 uid=0 ct=3 lid=0 tim=8736422346 hv=2888850659 ad='342e1ff0'
select type#,blocks,extents,minexts,maxexts,extsize,extpct,user#,iniexts,NVL(lists,65535),NVL(groups,65535),cachehint,hwmincr, NVL(spare1,0),NVL(scanhint,0) from seg$ where ts#=:1 and file#=:2 and block#=:3
END OF STMT
PARSE #3:c=0,e=22,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736422342
BINDS #3:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f850c bln=22 avl=02 flg=05
value=4
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f84e8 bln=24 avl=02 flg=05
value=4
Bind#2
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f84c4 bln=24 avl=02 flg=05
value=19
EXEC #3:c=0,e=176,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736422613
FETCH #3:c=0,e=49,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736422687
STAT #3 id=1 cnt=1 pid=0 pos=1 bj=14 p='TABLE ACCESS CLUSTER SEG$ (cr=3 pr=0 pw=0 time=50 us)'
STAT #3 id=2 cnt=1 pid=1 pos=1 bj=9 p='INDEX UNIQUE SCAN I_FILE#_BLOCK# (cr=2 pr=0 pw=0 time=18 us)'
=====================
PARSING IN CURSOR #5 len=169 dep=1 uid=0 ct=3 lid=0 tim=8736424127 hv=1173719687 ad='342e886c'
select col#, grantee#, privilege#,max(mod(nvl(option$,0),2)) from objauth$ where obj#=:1 and col# is not null group by privilege#, col#, grantee# order by col#, grantee#
END OF STMT
PARSE #5:c=0,e=1277,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736424122
BINDS #5:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f850c bln=22 avl=04 flg=05
value=51151
EXEC #5:c=0,e=1281,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736425507
FETCH #5:c=0,e=55,p=0,cr=2,cu=0,mis=0,r=0,dep=1,og=4,tim=8736425614
STAT #5 id=1 cnt=0 pid=0 pos=1 bj=0 p='SORT GROUP BY (cr=2 pr=0 pw=0 time=143 us)'
STAT #5 id=2 cnt=0 pid=1 pos=1 bj=57 p='TABLE ACCESS BY INDEX ROWID OBJAUTH$ (cr=2 pr=0 pw=0 time=105 us)'
STAT #5 id=3 cnt=0 pid=2 pos=1 bj=103 p='INDEX RANGE SCAN I_OBJAUTH1 (cr=2 pr=0 pw=0 time=48 us)'
=====================
PARSING IN CURSOR #6 len=151 dep=1 uid=0 ct=3 lid=0 tim=8736426837 hv=4139184264 ad='342e7f44'
select grantee#,privilege#,nvl(col#,0),max(mod(nvl(option$,0),2))from objauth$ where obj#=:1 group by grantee#,privilege#,nvl(col#,0) order by grantee#
END OF STMT
PARSE #6:c=0,e=1085,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736426832
BINDS #6:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f72f8 bln=22 avl=04 flg=05
value=51151
EXEC #6:c=0,e=1186,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736428120
FETCH #6:c=0,e=46,p=0,cr=2,cu=0,mis=0,r=0,dep=1,og=4,tim=8736428206
STAT #6 id=1 cnt=0 pid=0 pos=1 bj=0 p='SORT GROUP BY (cr=2 pr=0 pw=0 time=71 us)'
STAT #6 id=2 cnt=0 pid=1 pos=1 bj=57 p='TABLE ACCESS BY INDEX ROWID OBJAUTH$ (cr=2 pr=0 pw=0 time=40 us)'
STAT #6 id=3 cnt=0 pid=2 pos=1 bj=103 p='INDEX RANGE SCAN I_OBJAUTH1 (cr=2 pr=0 pw=0 time=26 us)'
=====================
PARSING IN CURSOR #3 len=69 dep=1 uid=0 ct=3 lid=0 tim=8736429893 hv=1471956217 ad='342e937c'
select con#,obj#,rcon#,enabled,nvl(defer,0) from cdef$ where robj#=:1
END OF STMT
PARSE #3:c=0,e=1558,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736429888
BINDS #3:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f648c bln=22 avl=04 flg=05
value=51151
EXEC #3:c=0,e=1130,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736431127
FETCH #3:c=0,e=28,p=0,cr=1,cu=0,mis=0,r=0,dep=1,og=4,tim=8736431194
STAT #3 id=1 cnt=0 pid=0 pos=1 bj=31 p='TABLE ACCESS BY INDEX ROWID CDEF$ (cr=1 pr=0 pw=0 time=36 us)'
STAT #3 id=2 cnt=0 pid=1 pos=1 bj=52 p='INDEX RANGE SCAN I_CDEF3 (cr=1 pr=0 pw=0 time=21 us)'
=====================
PARSING IN CURSOR #5 len=146 dep=1 uid=0 ct=3 lid=0 tim=8736433111 hv=2107929772 ad='342eae18'
select con#,type#,condlength,intcols,robj#,rcon#,match#,refact,nvl(enabled,0),rowid,cols,nvl(defer,0),mtime,nvl(spare1,0) from cdef$ where obj#=:1
END OF STMT
PARSE #5:c=0,e=1771,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736433107
BINDS #5:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f5924 bln=22 avl=04 flg=05
value=51151
EXEC #5:c=0,e=1199,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736434467
FETCH #5:c=0,e=54,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736434563
=====================
PARSING IN CURSOR #6 len=70 dep=1 uid=0 ct=3 lid=0 tim=8736435869 hv=3849548163 ad='342e8e8c'
select intcol#,nvl(pos#,0),col#,nvl(spare1,0) from ccol$ where con#=:1
END OF STMT
PARSE #6:c=0,e=1250,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736435863
BINDS #6:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0da0937c bln=22 avl=03 flg=05
value=5141
EXEC #6:c=0,e=1075,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736437063
WAIT #6: nam='db file sequential read' ela= 13185 file#=1 block#=26976 blocks=1 obj#=-1 tim=8736450749
FETCH #6:c=15625,e=13758,p=1,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736450861
FETCH #6:c=0,e=35,p=0,cr=1,cu=0,mis=0,r=0,dep=1,og=4,tim=8736450947
STAT #6 id=1 cnt=1 pid=0 pos=1 bj=32 p='TABLE ACCESS BY INDEX ROWID CCOL$ (cr=4 pr=1 pw=0 time=13754 us)'
STAT #6 id=2 cnt=1 pid=1 pos=1 bj=54 p='INDEX RANGE SCAN I_CCOL1 (cr=3 pr=1 pw=0 time=13754 us)'
FETCH #5:c=0,e=30,p=0,cr=2,cu=0,mis=0,r=1,dep=1,og=4,tim=8736451088
BINDS #3:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0da0937c bln=22 avl=03 flg=05
value=5142
=====================
PARSING IN CURSOR #3 len=70 dep=1 uid=0 ct=3 lid=0 tim=8736451238 hv=3849548163 ad='342e8e8c'
select intcol#,nvl(pos#,0),col#,nvl(spare1,0) from ccol$ where con#=:1
END OF STMT
EXEC #3:c=0,e=89,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736451234
FETCH #3:c=0,e=34,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736451318
FETCH #3:c=0,e=12,p=0,cr=1,cu=0,mis=0,r=0,dep=1,og=4,tim=8736451355
FETCH #5:c=0,e=10,p=0,cr=1,cu=0,mis=0,r=0,dep=1,og=4,tim=8736451402
STAT #5 id=1 cnt=2 pid=0 pos=1 bj=31 p='TABLE ACCESS BY INDEX ROWID CDEF$ (cr=6 pr=0 pw=0 time=54 us)'
STAT #5 id=2 cnt=2 pid=1 pos=1 bj=51 p='INDEX RANGE SCAN I_CDEF2 (cr=4 pr=0 pw=0 time=37 us)'
BINDS #6:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f850c bln=22 avl=04 flg=05
value=51149
=====================
PARSING IN CURSOR #6 len=169 dep=1 uid=0 ct=3 lid=0 tim=8736451657 hv=1173719687 ad='342e886c'
select col#, grantee#, privilege#,max(mod(nvl(option$,0),2)) from objauth$ where obj#=:1 and col# is not null group by privilege#, col#, grantee# order by col#, grantee#
END OF STMT
EXEC #6:c=0,e=112,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736451652
FETCH #6:c=0,e=70,p=0,cr=2,cu=0,mis=0,r=0,dep=1,og=4,tim=8736451779
BINDS #3:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f72f8 bln=22 avl=04 flg=05
value=51149
=====================
PARSING IN CURSOR #3 len=151 dep=1 uid=0 ct=3 lid=0 tim=8736451945 hv=4139184264 ad='342e7f44'
select grantee#,privilege#,nvl(col#,0),max(mod(nvl(option$,0),2))from objauth$ where obj#=:1 group by grantee#,privilege#,nvl(col#,0) order by grantee#
END OF STMT
EXEC #3:c=0,e=98,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736451940
FETCH #3:c=0,e=42,p=0,cr=2,cu=0,mis=0,r=0,dep=1,og=4,tim=8736452044
BINDS #5:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f648c bln=22 avl=04 flg=05
value=51149
=====================
PARSING IN CURSOR #5 len=69 dep=1 uid=0 ct=3 lid=0 tim=8736452230 hv=1471956217 ad='342e937c'
select con#,obj#,rcon#,enabled,nvl(defer,0) from cdef$ where robj#=:1
END OF STMT
EXEC #5:c=0,e=89,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736452227
FETCH #5:c=0,e=39,p=0,cr=2,cu=0,mis=0,r=1,dep=1,og=4,tim=8736452317
FETCH #5:c=0,e=14,p=0,cr=1,cu=0,mis=0,r=0,dep=1,og=4,tim=8736452358
BINDS #6:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f5924 bln=22 avl=04 flg=05
value=51149
=====================
PARSING IN CURSOR #6 len=146 dep=1 uid=0 ct=3 lid=0 tim=8736452512 hv=2107929772 ad='342eae18'
select con#,type#,condlength,intcols,robj#,rcon#,match#,refact,nvl(enabled,0),rowid,cols,nvl(defer,0),mtime,nvl(spare1,0) from cdef$ where obj#=:1
END OF STMT
EXEC #6:c=0,e=90,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736452508
FETCH #6:c=0,e=39,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736452599
BINDS #3:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0da0937c bln=22 avl=03 flg=05
value=5140
=====================
PARSING IN CURSOR #3 len=70 dep=1 uid=0 ct=3 lid=0 tim=8736452758 hv=3849548163 ad='342e8e8c'
select intcol#,nvl(pos#,0),col#,nvl(spare1,0) from ccol$ where con#=:1
END OF STMT
EXEC #3:c=0,e=104,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736452754
FETCH #3:c=0,e=36,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736452844
FETCH #3:c=0,e=13,p=0,cr=1,cu=0,mis=0,r=0,dep=1,og=4,tim=8736452882
FETCH #6:c=0,e=13,p=0,cr=1,cu=0,mis=0,r=0,dep=1,og=4,tim=8736452932
=====================
PARSING IN CURSOR #5 len=210 dep=1 uid=0 ct=3 lid=0 tim=8736454113 hv=864012087 ad='34266b84'
select /*+ rule */ bucket_cnt, row_cnt, cache_cnt, null_cnt, timestamp#, sample_size, minimum, maximum, distcnt, lowval, hival, density, col#, spare1, spare2, avgcln from hist_head$ where obj#=:1 and intcol#=:2
END OF STMT
PARSE #5:c=0,e=908,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=3,tim=8736454108
BINDS #5:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f4198 bln=22 avl=04 flg=05
value=51149
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f4174 bln=24 avl=02 flg=05
value=1
EXEC #5:c=0,e=2528,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=3,tim=8736456767
WAIT #5: nam='db file sequential read' ela= 389 file#=1 block#=59472 blocks=1 obj#=-1 tim=8736457232
WAIT #5: nam='db file sequential read' ela= 336 file#=1 block#=59488 blocks=1 obj#=-1 tim=8736457633
FETCH #5:c=0,e=871,p=2,cr=3,cu=0,mis=0,r=1,dep=1,og=3,tim=8736457679
STAT #5 id=1 cnt=1 pid=0 pos=1 bj=255 p='TABLE ACCESS BY INDEX ROWID HIST_HEAD$ (cr=3 pr=2 pw=0 time=869 us)'
STAT #5 id=2 cnt=1 pid=1 pos=1 bj=257 p='INDEX RANGE SCAN I_HH_OBJ#_INTCOL# (cr=2 pr=1 pw=0 time=471 us)'
BINDS #3:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f4198 bln=22 avl=04 flg=05
value=51151
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f4174 bln=24 avl=02 flg=05
value=8
=====================
PARSING IN CURSOR #3 len=210 dep=1 uid=0 ct=3 lid=0 tim=8736457979 hv=864012087 ad='34266b84'
select /*+ rule */ bucket_cnt, row_cnt, cache_cnt, null_cnt, timestamp#, sample_size, minimum, maximum, distcnt, lowval, hival, density, col#, spare1, spare2, avgcln from hist_head$ where obj#=:1 and intcol#=:2
END OF STMT
EXEC #3:c=0,e=121,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=3,tim=8736457976
FETCH #3:c=0,e=42,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=3,tim=8736458069
STAT #3 id=1 cnt=2 pid=0 pos=1 bj=255 p='TABLE ACCESS BY INDEX ROWID HIST_HEAD$ (cr=6 pr=2 pw=0 time=912 us)'
STAT #3 id=2 cnt=2 pid=1 pos=1 bj=257 p='INDEX RANGE SCAN I_HH_OBJ#_INTCOL# (cr=4 pr=1 pw=0 time=493 us)'
=====================
PARSING IN CURSOR #6 len=121 dep=1 uid=0 ct=3 lid=0 tim=8736458754 hv=3150898423 ad='342661e4'
select /*+ rule */ bucket, endpoint, col#, epvalue from histgrm$ where obj#=:1 and intcol#=:2 and row#=:3 order by bucket
END OF STMT
PARSE #6:c=0,e=578,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=3,tim=8736458749
BINDS #6:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f2a0c bln=22 avl=04 flg=05
value=51151
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f29e8 bln=24 avl=02 flg=05
value=8
Bind#2
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f29c4 bln=24 avl=01 flg=05
value=0
EXEC #6:c=0,e=3910,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=3,tim=8736462779
WAIT #6: nam='db file sequential read' ela= 6067 file#=1 block#=1751 blocks=1 obj#=-1 tim=8736468938
WAIT #6: nam='db file sequential read' ela= 9051 file#=1 block#=60352 blocks=1 obj#=-1 tim=8736478075
FETCH #6:c=0,e=15407,p=2,cr=3,cu=0,mis=0,r=2,dep=1,og=3,tim=8736478232
STAT #6 id=1 cnt=2 pid=0 pos=1 bj=0 p='SORT ORDER BY (cr=3 pr=2 pw=0 time=15404 us)'
STAT #6 id=2 cnt=2 pid=1 pos=1 bj=253 p='TABLE ACCESS CLUSTER HISTGRM$ (cr=3 pr=2 pw=0 time=15342 us)'
STAT #6 id=3 cnt=1 pid=2 pos=1 bj=252 p='INDEX UNIQUE SCAN I_OBJ#_INTCOL# (cr=2 pr=1 pw=0 time=6172 us)'
BINDS #5:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f4198 bln=22 avl=04 flg=05
value=51149
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f4174 bln=24 avl=02 flg=05
value=2
=====================
PARSING IN CURSOR #5 len=210 dep=1 uid=0 ct=3 lid=0 tim=8736478705 hv=864012087 ad='34266b84'
select /*+ rule */ bucket_cnt, row_cnt, cache_cnt, null_cnt, timestamp#, sample_size, minimum, maximum, distcnt, lowval, hival, density, col#, spare1, spare2, avgcln from hist_head$ where obj#=:1 and intcol#=:2
END OF STMT
EXEC #5:c=0,e=144,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=3,tim=8736478701
FETCH #5:c=0,e=54,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=3,tim=8736478811
STAT #5 id=1 cnt=3 pid=0 pos=1 bj=255 p='TABLE ACCESS BY INDEX ROWID HIST_HEAD$ (cr=9 pr=2 pw=0 time=968 us)'
STAT #5 id=2 cnt=3 pid=1 pos=1 bj=257 p='INDEX RANGE SCAN I_HH_OBJ#_INTCOL# (cr=6 pr=1 pw=0 time=521 us)'
BINDS #3:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f4198 bln=22 avl=04 flg=05
value=51151
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f4174 bln=24 avl=02 flg=05
value=3
=====================
PARSING IN CURSOR #3 len=210 dep=1 uid=0 ct=3 lid=0 tim=8736479095 hv=864012087 ad='34266b84'
select /*+ rule */ bucket_cnt, row_cnt, cache_cnt, null_cnt, timestamp#, sample_size, minimum, maximum, distcnt, lowval, hival, density, col#, spare1, spare2, avgcln from hist_head$ where obj#=:1 and intcol#=:2
END OF STMT
EXEC #3:c=0,e=124,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=3,tim=8736479091
FETCH #3:c=0,e=40,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=3,tim=8736479184
STAT #3 id=1 cnt=4 pid=0 pos=1 bj=255 p='TABLE ACCESS BY INDEX ROWID HIST_HEAD$ (cr=12 pr=2 pw=0 time=1009 us)'
STAT #3 id=2 cnt=4 pid=1 pos=1 bj=257 p='INDEX RANGE SCAN I_HH_OBJ#_INTCOL# (cr=8 pr=1 pw=0 time=540 us)'
BINDS #6:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f2a0c bln=22 avl=04 flg=05
value=51151
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f29e8 bln=24 avl=02 flg=05
value=3
Bind#2
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f29c4 bln=24 avl=01 flg=05
value=0
=====================
PARSING IN CURSOR #6 len=121 dep=1 uid=0 ct=3 lid=0 tim=8736479445 hv=3150898423 ad='342661e4'
select /*+ rule */ bucket, endpoint, col#, epvalue from histgrm$ where obj#=:1 and intcol#=:2 and row#=:3 order by bucket
END OF STMT
EXEC #6:c=0,e=161,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=3,tim=8736479440
WAIT #6: nam='db file sequential read' ela= 4273 file#=1 block#=61231 blocks=1 obj#=-1 tim=8736483819
FETCH #6:c=0,e=4446,p=1,cr=3,cu=0,mis=0,r=4,dep=1,og=3,tim=8736483943
=====================
--(2)以下是我要执行的select语句
PARSING IN CURSOR #4 len=61 dep=0 uid=54 ct=3 lid=54 tim=8736484564 hv=123666006 ad='2f5e9418'
select job,dname from dept,emp where dept.deptno=emp.deptno
END OF STMT
PARSE #4:c=78125,e=144657,p=12,cr=126,cu=0,mis=1,r=0,dep=0,og=1,tim=8736484558
BINDS #4:
EXEC #4:c=0,e=58,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736484689
WAIT #4: nam='SQL*Net message to client' ela= 5 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736484729
WAIT #4: nam='SQL*Net message from client' ela= 278 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736485061
WAIT #4: nam='db file sequential read' ela= 14145 file#=4 block#=27 blocks=1 obj#=51151 tim=8736499440
WAIT #4: nam='db file scattered read' ela= 735 file#=4 block#=28 blocks=5 obj#=51151 tim=8736500310
WAIT #4: nam='db file sequential read' ela= 1903 file#=4 block#=20 blocks=1 obj#=51150 tim=8736502338
WAIT #4: nam='db file scattered read' ela= 527 file#=4 block#=9 blocks=8 obj#=51149 tim=8736502961
WAIT #4: nam='SQL*Net message to client' ela= 4 driver id=1413697536 #bytes=1 p3=0 obj#=51149 tim=8736503040
FETCH #4:c=0,e=18234,p=15,cr=16,cu=0,mis=0,r=7,dep=0,og=1,tim=8736503338
WAIT #4: nam='SQL*Net message from client' ela= 9416 driver id=1413697536 #bytes=1 p3=0 obj#=51149 tim=8736512847
STAT #4 id=1 cnt=7 pid=0 pos=1 bj=0 p='NESTED LOOPS (cr=16 pr=15 pw=0 time=18324 us)'
STAT #4 id=2 cnt=7 pid=1 pos=1 bj=51151 p='TABLE ACCESS FULL EMP (cr=7 pr=6 pw=0 time=15311 us)'
STAT #4 id=3 cnt=7 pid=1 pos=2 bj=51149 p='TABLE ACCESS BY INDEX ROWID DEPT (cr=9 pr=9 pw=0 time=2846 us)'
STAT #4 id=4 cnt=7 pid=3 pos=1 bj=51150 p='INDEX UNIQUE SCAN PK_DEPT (cr=2 pr=1 pw=0 time=2086 us)'
=====================
PARSING IN CURSOR #5 len=61 dep=0 uid=54 ct=47 lid=54 tim=8736513088 hv=356401299 ad='2f727528'
begin :id := sys.dbms_transaction.local_transaction_id; end;
END OF STMT
PARSE #5:c=0,e=39,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736513084
BINDS #5:
kkscoacd
Bind#0
acdty=01 mxl=2000(2000) mxlc=00 mal=00 scl=00 pre=00
acflg=01 fl2=1000000 frm=01 csi=852 siz=2000 ff=0
kxsbbbfp=0da09fdc bln=2000 avl=00 flg=05
WAIT #5: nam='SQL*Net message to client' ela= 4 driver id=1413697536 #bytes=1 p3=0 obj#=51149 tim=8736513266
EXEC #5:c=15625,e=144,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=8736513289
WAIT #5: nam='SQL*Net message from client' ela= 13343 driver id=1413697536 #bytes=1 p3=0 obj#=51149 tim=8736526704
=====================
PARSING IN CURSOR #3 len=21 dep=0 uid=54 ct=3 lid=54 tim=8736526901 hv=2564406738 ad='2f614370'
select 'x' from dual
END OF STMT
PARSE #3:c=0,e=64,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736526896
BINDS #3:
EXEC #3:c=0,e=46,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736527003
WAIT #3: nam='SQL*Net message to client' ela= 4 driver id=1413697536 #bytes=1 p3=0 obj#=51149 tim=8736527039
WAIT #3: nam='SQL*Net message from client' ela= 249 driver id=1413697536 #bytes=1 p3=0 obj#=51149 tim=8736527340
WAIT #3: nam='SQL*Net message to client' ela= 2 driver id=1413697536 #bytes=1 p3=0 obj#=51149 tim=8736527409
FETCH #3:c=0,e=46,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=8736527431
WAIT #3: nam='SQL*Net message from client' ela= 8387 driver id=1413697536 #bytes=1 p3=0 obj#=51149 tim=8736535876
STAT #3 id=1 cnt=1 pid=0 pos=1 bj=0 p='FAST DUAL (cr=0 pr=0 pw=0 time=13 us)'
=====================
PARSING IN CURSOR #6 len=61 dep=0 uid=54 ct=47 lid=54 tim=8736536098 hv=356401299 ad='2f727528'
begin :id := sys.dbms_transaction.local_transaction_id; end;
END OF STMT
PARSE #6:c=0,e=44,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736536093
BINDS #6:
kkscoacd
Bind#0
acdty=01 mxl=2000(2000) mxlc=00 mal=00 scl=00 pre=00
acflg=01 fl2=1000000 frm=01 csi=852 siz=2000 ff=0
kxsbbbfp=0da09fdc bln=2000 avl=00 flg=05
WAIT #6: nam='SQL*Net message to client' ela= 5 driver id=1413697536 #bytes=1 p3=0 obj#=51149 tim=8736536305
EXEC #6:c=0,e=157,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=8736536329
WAIT #6: nam='SQL*Net message from client' ela= 311 driver id=1413697536 #bytes=1 p3=0 obj#=51149 tim=8736536697
=====================
PARSING IN CURSOR #5 len=116 dep=1 uid=0 ct=3 lid=0 tim=8736537207 hv=854877822 ad='342f461c'
select o.owner#,o.name,o.namespace,o.remoteowner,o.linkname,o.subname,o.dataobj#,o.flags from obj$ o where o.obj#=:1
END OF STMT
PARSE #5:c=0,e=34,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736537202
BINDS #5:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f168c bln=22 avl=04 flg=05
value=51151
EXEC #5:c=0,e=103,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736537409
FETCH #5:c=0,e=50,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736537487
STAT #5 id=1 cnt=1 pid=0 pos=1 bj=18 p='TABLE ACCESS BY INDEX ROWID OBJ$ (cr=3 pr=0 pw=0 time=52 us)'
STAT #5 id=2 cnt=1 pid=1 pos=1 bj=36 p='INDEX UNIQUE SCAN I_OBJ1 (cr=2 pr=0 pw=0 time=23 us)'
=====================
PARSING IN CURSOR #3 len=227 dep=1 uid=0 ct=3 lid=0 tim=8736538169 hv=2190775527 ad='303dc5d8'
select u.name,o.name, t.update$, t.insert$, t.delete$, t.enabled from obj$ o,user$ u,trigger$ t where t.baseobject=:1 and t.obj#=o.obj# and o.owner#=u.user# and bitand(property,16)=0 and bitand(property,8)=0 order by o.obj#
END OF STMT
PARSE #3:c=0,e=507,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736538163
=====================
PARSING IN CURSOR #6 len=210 dep=2 uid=0 ct=3 lid=0 tim=8736539017 hv=864012087 ad='34266b84'
select /*+ rule */ bucket_cnt, row_cnt, cache_cnt, null_cnt, timestamp#, sample_size, minimum, maximum, distcnt, lowval, hival, density, col#, spare1, spare2, avgcln from hist_head$ where obj#=:1 and intcol#=:2
END OF STMT
PARSE #6:c=0,e=45,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=3,tim=8736539012
BINDS #6:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f0d4c bln=22 avl=02 flg=05
value=81
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f0d28 bln=24 avl=02 flg=05
value=3
EXEC #6:c=0,e=124,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=3,tim=8736539253
FETCH #6:c=0,e=50,p=0,cr=3,cu=0,mis=0,r=1,dep=2,og=3,tim=8736539323
STAT #6 id=1 cnt=1 pid=0 pos=1 bj=255 p='TABLE ACCESS BY INDEX ROWID HIST_HEAD$ (cr=3 pr=0 pw=0 time=53 us)'
STAT #6 id=2 cnt=1 pid=1 pos=1 bj=257 p='INDEX RANGE SCAN I_HH_OBJ#_INTCOL# (cr=2 pr=0 pw=0 time=27 us)'
BINDS #5:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f0d4c bln=22 avl=02 flg=05
value=81
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f0d28 bln=24 avl=02 flg=05
value=4
=====================
PARSING IN CURSOR #5 len=210 dep=2 uid=0 ct=3 lid=0 tim=8736539556 hv=864012087 ad='34266b84'
select /*+ rule */ bucket_cnt, row_cnt, cache_cnt, null_cnt, timestamp#, sample_size, minimum, maximum, distcnt, lowval, hival, density, col#, spare1, spare2, avgcln from hist_head$ where obj#=:1 and intcol#=:2
END OF STMT
EXEC #5:c=0,e=103,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=3,tim=8736539552
FETCH #5:c=0,e=32,p=0,cr=3,cu=0,mis=0,r=1,dep=2,og=3,tim=8736539630
STAT #5 id=1 cnt=2 pid=0 pos=1 bj=255 p='TABLE ACCESS BY INDEX ROWID HIST_HEAD$ (cr=6 pr=0 pw=0 time=87 us)'
STAT #5 id=2 cnt=2 pid=1 pos=1 bj=257 p='INDEX RANGE SCAN I_HH_OBJ#_INTCOL# (cr=4 pr=0 pw=0 time=42 us)'
BINDS #6:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f0d4c bln=22 avl=02 flg=05
value=81
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f0d28 bln=24 avl=02 flg=05
value=5
=====================
PARSING IN CURSOR #6 len=210 dep=2 uid=0 ct=3 lid=0 tim=8736539947 hv=864012087 ad='34266b84'
select /*+ rule */ bucket_cnt, row_cnt, cache_cnt, null_cnt, timestamp#, sample_size, minimum, maximum, distcnt, lowval, hival, density, col#, spare1, spare2, avgcln from hist_head$ where obj#=:1 and intcol#=:2
END OF STMT
EXEC #6:c=0,e=111,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=3,tim=8736539943
FETCH #6:c=0,e=38,p=0,cr=3,cu=0,mis=0,r=1,dep=2,og=3,tim=8736540027
STAT #6 id=1 cnt=3 pid=0 pos=1 bj=255 p='TABLE ACCESS BY INDEX ROWID HIST_HEAD$ (cr=9 pr=0 pw=0 time=126 us)'
STAT #6 id=2 cnt=3 pid=1 pos=1 bj=257 p='INDEX RANGE SCAN I_HH_OBJ#_INTCOL# (cr=6 pr=0 pw=0 time=60 us)'
BINDS #5:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f0d4c bln=22 avl=02 flg=05
value=81
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f0d28 bln=24 avl=02 flg=05
value=13
=====================
PARSING IN CURSOR #5 len=210 dep=2 uid=0 ct=3 lid=0 tim=8736540234 hv=864012087 ad='34266b84'
select /*+ rule */ bucket_cnt, row_cnt, cache_cnt, null_cnt, timestamp#, sample_size, minimum, maximum, distcnt, lowval, hival, density, col#, spare1, spare2, avgcln from hist_head$ where obj#=:1 and intcol#=:2
END OF STMT
EXEC #5:c=0,e=102,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=3,tim=8736540230
FETCH #5:c=0,e=31,p=0,cr=3,cu=0,mis=0,r=1,dep=2,og=3,tim=8736540306
STAT #5 id=1 cnt=4 pid=0 pos=1 bj=255 p='TABLE ACCESS BY INDEX ROWID HIST_HEAD$ (cr=12 pr=0 pw=0 time=159 us)'
STAT #5 id=2 cnt=4 pid=1 pos=1 bj=257 p='INDEX RANGE SCAN I_HH_OBJ#_INTCOL# (cr=8 pr=0 pw=0 time=74 us)'
BINDS #3:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=00 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0da08b64 bln=22 avl=04 flg=05
value=51151
EXEC #3:c=0,e=3493,p=0,cr=12,cu=0,mis=1,r=0,dep=1,og=4,tim=8736541728
FETCH #3:c=0,e=61,p=0,cr=1,cu=0,mis=0,r=0,dep=1,og=4,tim=8736541815
STAT #3 id=1 cnt=0 pid=0 pos=1 bj=0 p='SORT ORDER BY (cr=1 pr=0 pw=0 time=105 us)'
STAT #3 id=2 cnt=0 pid=1 pos=1 bj=0 p='NESTED LOOPS (cr=1 pr=0 pw=0 time=80 us)'
STAT #3 id=3 cnt=0 pid=2 pos=1 bj=0 p='NESTED LOOPS (cr=1 pr=0 pw=0 time=69 us)'
STAT #3 id=4 cnt=0 pid=3 pos=1 bj=81 p='TABLE ACCESS BY INDEX ROWID TRIGGER$ (cr=1 pr=0 pw=0 time=56 us)'
STAT #3 id=5 cnt=0 pid=4 pos=1 bj=125 p='INDEX RANGE SCAN I_TRIGGER1 (cr=1 pr=0 pw=0 time=22 us)'
STAT #3 id=6 cnt=0 pid=3 pos=2 bj=18 p='TABLE ACCESS BY INDEX ROWID OBJ$ (cr=0 pr=0 pw=0 time=0 us)'
STAT #3 id=7 cnt=0 pid=6 pos=1 bj=36 p='INDEX UNIQUE SCAN I_OBJ1 (cr=0 pr=0 pw=0 time=0 us)'
STAT #3 id=8 cnt=0 pid=2 pos=2 bj=22 p='TABLE ACCESS CLUSTER USER$ (cr=0 pr=0 pw=0 time=0 us)'
STAT #3 id=9 cnt=0 pid=8 pos=1 bj=11 p='INDEX UNIQUE SCAN I_USER# (cr=0 pr=0 pw=0 time=0 us)'
BINDS #6:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f0d4c bln=22 avl=04 flg=05
value=51151
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d6f0d28 bln=24 avl=02 flg=05
value=2
=====================
PARSING IN CURSOR #6 len=210 dep=1 uid=0 ct=3 lid=0 tim=8736542284 hv=864012087 ad='34266b84'
select /*+ rule */ bucket_cnt, row_cnt, cache_cnt, null_cnt, timestamp#, sample_size, minimum, maximum, distcnt, lowval, hival, density, col#, spare1, spare2, avgcln from hist_head$ where obj#=:1 and intcol#=:2
END OF STMT
EXEC #6:c=0,e=125,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=3,tim=8736542281
FETCH #6:c=0,e=46,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=3,tim=8736542387
STAT #6 id=1 cnt=5 pid=0 pos=1 bj=255 p='TABLE ACCESS BY INDEX ROWID HIST_HEAD$ (cr=15 pr=0 pw=0 time=205 us)'
STAT #6 id=2 cnt=5 pid=1 pos=1 bj=257 p='INDEX RANGE SCAN I_HH_OBJ#_INTCOL# (cr=10 pr=0 pw=0 time=95 us)'
=====================
PARSING IN CURSOR #4 len=33 dep=0 uid=54 ct=6 lid=54 tim=8736542691 hv=3718948346 ad='2f5e8dac'
update emp set ename=ename||'a'
END OF STMT
PARSE #4:c=0,e=5880,p=0,cr=19,cu=0,mis=1,r=0,dep=0,og=1,tim=8736542687
BINDS #4:
WAIT #4: nam='db file sequential read' ela= 16810 file#=2 block#=121 blocks=1 obj#=0 tim=8736559839
WAIT #4: nam='db file sequential read' ela= 6545 file#=2 block#=1882 blocks=1 obj#=0 tim=8736566460
EXEC #4:c=0,e=23952,p=2,cr=7,cu=16,mis=0,r=7,dep=0,og=1,tim=8736566695
WAIT #4: nam='SQL*Net message to client' ela= 4 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736566778
WAIT #4: nam='SQL*Net message from client' ela= 10889 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736577702
STAT #4 id=1 cnt=0 pid=0 pos=1 bj=0 p='UPDATE EMP (cr=7 pr=2 pw=0 time=23891 us)'
STAT #4 id=2 cnt=7 pid=1 pos=1 bj=51151 p='TABLE ACCESS FULL EMP (cr=7 pr=0 pw=0 time=92 us)'
=====================
PARSING IN CURSOR #5 len=21 dep=0 uid=54 ct=3 lid=54 tim=8736577920 hv=2564406738 ad='2f614370'
select 'x' from dual
END OF STMT
PARSE #5:c=0,e=43,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736577915
BINDS #5:
EXEC #5:c=0,e=47,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736578015
WAIT #5: nam='SQL*Net message to client' ela= 4 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736578048
WAIT #5: nam='SQL*Net message from client' ela= 212 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736578304
WAIT #5: nam='SQL*Net message to client' ela= 3 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736578372
FETCH #5:c=0,e=47,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=8736578395
WAIT #5: nam='SQL*Net message from client' ela= 8450 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736586901
STAT #5 id=1 cnt=1 pid=0 pos=1 bj=0 p='FAST DUAL (cr=0 pr=0 pw=0 time=12 us)'
=====================
PARSING IN CURSOR #3 len=61 dep=0 uid=54 ct=47 lid=54 tim=8736587114 hv=356401299 ad='2f727528'
begin :id := sys.dbms_transaction.local_transaction_id; end;
END OF STMT
PARSE #3:c=0,e=39,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736587110
BINDS #3:
kkscoacd
Bind#0
acdty=01 mxl=2000(2000) mxlc=00 mal=00 scl=00 pre=00
acflg=01 fl2=1000000 frm=01 csi=852 siz=2000 ff=0
kxsbbbfp=0d70f018 bln=2000 avl=00 flg=05
WAIT #3: nam='SQL*Net message to client' ela= 5 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736587324
EXEC #3:c=0,e=174,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=8736587350
WAIT #3: nam='SQL*Net message from client' ela= 324 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736587729
=====================
PARSING IN CURSOR #4 len=116 dep=1 uid=0 ct=3 lid=0 tim=8736588753 hv=854877822 ad='342f461c'
select o.owner#,o.name,o.namespace,o.remoteowner,o.linkname,o.subname,o.dataobj#,o.flags from obj$ o where o.obj#=:1
END OF STMT
PARSE #4:c=0,e=43,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736588748
BINDS #4:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d70ffe8 bln=22 avl=04 flg=05
value=51149
EXEC #4:c=0,e=95,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,tim=8736588951
FETCH #4:c=0,e=39,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,tim=8736589018
STAT #4 id=1 cnt=1 pid=0 pos=1 bj=18 p='TABLE ACCESS BY INDEX ROWID OBJ$ (cr=3 pr=0 pw=0 time=41 us)'
STAT #4 id=2 cnt=1 pid=1 pos=1 bj=36 p='INDEX UNIQUE SCAN I_OBJ1 (cr=2 pr=0 pw=0 time=17 us)'
=====================
PARSING IN CURSOR #5 len=402 dep=1 uid=0 ct=3 lid=0 tim=8736589554 hv=3607805727 ad='3429fa10'
select parttype, partcnt, partkeycols, flags, defts#, defpctfree, defpctused, definitrans, defmaxtrans, deftiniexts, defextsize, defminexts, defmaxexts, defextpct, deflists, defgroups, deflogging, spare1, mod(spare2, 256) subparttype, mod(trunc(spare2/256), 256) subpartkeycols, mod(trunc(spare2/65536), 65536) defsubpartcnt, mod(trunc(spare2/4294967296), 256) defhscflags from partobj$ where obj# = :1
END OF STMT
PARSE #5:c=0,e=411,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736589549
BINDS #5:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=00 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d70d674 bln=22 avl=04 flg=05
value=51149
EXEC #5:c=0,e=1104,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,tim=8736590732
FETCH #5:c=0,e=21,p=0,cr=1,cu=0,mis=0,r=0,dep=1,og=4,tim=8736590779
STAT #5 id=1 cnt=0 pid=0 pos=1 bj=262 p='TABLE ACCESS BY INDEX ROWID PARTOBJ$ (cr=1 pr=0 pw=0 time=28 us)'
STAT #5 id=2 cnt=0 pid=1 pos=1 bj=263 p='INDEX UNIQUE SCAN I_PARTOBJ$ (cr=1 pr=0 pw=0 time=13 us)'
=====================
PARSING IN CURSOR #3 len=210 dep=1 uid=0 ct=3 lid=0 tim=8736591148 hv=864012087 ad='34266b84'
select /*+ rule */ bucket_cnt, row_cnt, cache_cnt, null_cnt, timestamp#, sample_size, minimum, maximum, distcnt, lowval, hival, density, col#, spare1, spare2, avgcln from hist_head$ where obj#=:1 and intcol#=:2
END OF STMT
PARSE #3:c=0,e=34,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=3,tim=8736591143
BINDS #3:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d70e298 bln=22 avl=04 flg=05
value=51151
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d70e274 bln=24 avl=02 flg=05
value=1
EXEC #3:c=0,e=123,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=3,tim=8736591379
FETCH #3:c=0,e=47,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=3,tim=8736592569
STAT #3 id=1 cnt=1 pid=0 pos=1 bj=255 p='TABLE ACCESS BY INDEX ROWID HIST_HEAD$ (cr=3 pr=0 pw=0 time=43 us)'
STAT #3 id=2 cnt=1 pid=1 pos=1 bj=257 p='INDEX RANGE SCAN I_HH_OBJ#_INTCOL# (cr=2 pr=0 pw=0 time=19 us)'
BINDS #4:
kkscoacd
Bind#0
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d70e298 bln=22 avl=04 flg=05
value=51151
Bind#1
acdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
acflg=08 fl2=0001 frm=00 csi=00 siz=24 ff=0
kxsbbbfp=0d70e274 bln=24 avl=02 flg=05
value=6
=====================
PARSING IN CURSOR #4 len=210 dep=1 uid=0 ct=3 lid=0 tim=8736592906 hv=864012087 ad='34266b84'
select /*+ rule */ bucket_cnt, row_cnt, cache_cnt, null_cnt, timestamp#, sample_size, minimum, maximum, distcnt, lowval, hival, density, col#, spare1, spare2, avgcln from hist_head$ where obj#=:1 and intcol#=:2
END OF STMT
EXEC #4:c=0,e=129,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=3,tim=8736592902
FETCH #4:c=0,e=35,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=3,tim=8736592993
STAT #4 id=1 cnt=2 pid=0 pos=1 bj=255 p='TABLE ACCESS BY INDEX ROWID HIST_HEAD$ (cr=6 pr=0 pw=0 time=80 us)'
STAT #4 id=2 cnt=2 pid=1 pos=1 bj=257 p='INDEX RANGE SCAN I_HH_OBJ#_INTCOL# (cr=4 pr=0 pw=0 time=34 us)'
=====================
PARSING IN CURSOR #6 len=85 dep=0 uid=54 ct=7 lid=54 tim=8736593416 hv=3130576068 ad='3422821c'
delete from emp where empno in (select empno from dept where dept.dname='RESEARCH')
END OF STMT
PARSE #6:c=15625,e=5585,p=0,cr=10,cu=0,mis=1,r=0,dep=0,og=1,tim=8736593410
BINDS #6:
WAIT #6: nam='db file sequential read' ela= 325 file#=4 block#=11 blocks=1 obj#=51149 tim=8736593936
WAIT #6: nam='db file sequential read' ela= 1438 file#=4 block#=36 blocks=1 obj#=51152 tim=8736595534
WAIT #6: nam='db file sequential read' ela= 18700 file#=4 block#=2644 blocks=1 obj#=52284 tim=8736614325
WAIT #6: nam='db file sequential read' ela= 350 file#=4 block#=25 blocks=1 obj#=51151 tim=8736615282
EXEC #6:c=0,e=21917,p=4,cr=56,cu=36,mis=0,r=7,dep=0,og=1,tim=8736615391
WAIT #6: nam='SQL*Net message to client' ela= 4 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736615479
WAIT #6: nam='SQL*Net message from client' ela= 13405 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736628920
STAT #6 id=1 cnt=0 pid=0 pos=1 bj=0 p='DELETE EMP (cr=56 pr=4 pw=0 time=21863 us)'
STAT #6 id=2 cnt=7 pid=1 pos=1 bj=0 p='FILTER (cr=56 pr=1 pw=0 time=1082 us)'
STAT #6 id=3 cnt=7 pid=2 pos=1 bj=51151 p='TABLE ACCESS FULL EMP (cr=7 pr=0 pw=0 time=88 us)'
STAT #6 id=4 cnt=7 pid=2 pos=2 bj=0 p='FILTER (cr=49 pr=1 pw=0 time=714 us)'
STAT #6 id=5 cnt=7 pid=4 pos=1 bj=51149 p='TABLE ACCESS FULL DEPT (cr=49 pr=1 pw=0 time=604 us)'
=====================
PARSING IN CURSOR #5 len=21 dep=0 uid=54 ct=3 lid=54 tim=8736629168 hv=2564406738 ad='2f614370'
select 'x' from dual
END OF STMT
PARSE #5:c=0,e=28,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736629164
BINDS #5:
EXEC #5:c=0,e=48,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736629263
WAIT #5: nam='SQL*Net message to client' ela= 5 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736629297
WAIT #5: nam='SQL*Net message from client' ela= 243 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736629586
WAIT #5: nam='SQL*Net message to client' ela= 3 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736629657
FETCH #5:c=0,e=48,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=8736629679
WAIT #5: nam='SQL*Net message from client' ela= 6929 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736636665
STAT #5 id=1 cnt=1 pid=0 pos=1 bj=0 p='FAST DUAL (cr=0 pr=0 pw=0 time=11 us)'
=====================
PARSING IN CURSOR #3 len=61 dep=0 uid=54 ct=47 lid=54 tim=8736636876 hv=356401299 ad='2f727528'
begin :id := sys.dbms_transaction.local_transaction_id; end;
END OF STMT
PARSE #3:c=0,e=44,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736636872
BINDS #3:
kkscoacd
Bind#0
acdty=01 mxl=2000(2000) mxlc=00 mal=00 scl=00 pre=00
acflg=01 fl2=1000000 frm=01 csi=852 siz=2000 ff=0
kxsbbbfp=0d70c298 bln=2000 avl=00 flg=05
WAIT #3: nam='SQL*Net message to client' ela= 5 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736637076
EXEC #3:c=0,e=163,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=8736637102
WAIT #3: nam='SQL*Net message from client' ela= 316 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736637474
=====================
PARSING IN CURSOR #4 len=8 dep=0 uid=54 ct=44 lid=54 tim=8736637715 hv=1793260014 ad='0'
commit
END OF STMT
PARSE #4:c=0,e=114,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=8736637711
XCTEND rlbk=0, rd_only=0
EXEC #4:c=0,e=99,p=0,cr=0,cu=1,mis=0,r=0,dep=0,og=0,tim=8736637864
WAIT #4: nam='log file sync' ela= 488 buffer#=5349 p2=0 p3=0 obj#=-1 tim=8736638382
WAIT #4: nam='SQL*Net message to client' ela= 4 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736638443
WAIT #4: nam='SQL*Net message from client' ela= 24339 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736662816
=====================
PARSING IN CURSOR #6 len=21 dep=0 uid=54 ct=3 lid=54 tim=8736662987 hv=2564406738 ad='2f614370'
select 'x' from dual
END OF STMT
PARSE #6:c=0,e=32,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736662982
BINDS #6:
EXEC #6:c=0,e=51,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736663090
WAIT #6: nam='SQL*Net message to client' ela= 5 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736663123
WAIT #6: nam='SQL*Net message from client' ela= 276 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736663443
WAIT #6: nam='SQL*Net message to client' ela= 3 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736663518
FETCH #6:c=0,e=50,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=8736663544
WAIT #6: nam='SQL*Net message from client' ela= 7265 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736670869
STAT #6 id=1 cnt=1 pid=0 pos=1 bj=0 p='FAST DUAL (cr=0 pr=0 pw=0 time=14 us)'
=====================
PARSING IN CURSOR #5 len=61 dep=0 uid=54 ct=47 lid=54 tim=8736671079 hv=356401299 ad='2f727528'
begin :id := sys.dbms_transaction.local_transaction_id; end;
END OF STMT
PARSE #5:c=0,e=43,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736671074
BINDS #5:
kkscoacd
Bind#0
acdty=01 mxl=2000(2000) mxlc=00 mal=00 scl=00 pre=00
acflg=01 fl2=1000000 frm=01 csi=852 siz=2000 ff=0
kxsbbbfp=0d70c298 bln=2000 avl=00 flg=05
WAIT #5: nam='SQL*Net message to client' ela= 4 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736671274
EXEC #5:c=0,e=159,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=8736671301
WAIT #5: nam='SQL*Net message from client' ela= 345 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=8736671713
=====================
PARSING IN CURSOR #3 len=162 dep=0 uid=54 ct=2 lid=54 tim=8736672490 hv=1508839477 ad='2f5e8270'
insert into EMP (EMPNO, ENAME, JOB, MGR, HIREDATE, SAL, COMM, DEPTNO)
values (7876, 'ADAMS', 'CLERK', 7788, to_date('23-05-1987', 'dd-mm-yyyy'), 1100, null, 20)
END OF STMT
PARSE #3:c=0,e=658,p=0,cr=0,cu=0,mis=1,r=0,dep=0,og=1,tim=8736672484
BINDS #3:
WAIT #3: nam='db file sequential read' ela= 301 file#=4 block#=26 blocks=1 obj#=51151 tim=8736672988
WAIT #3: nam='db file sequential read' ela= 15194 file#=2 block#=73 blocks=1 obj#=0 tim=8736688287
WAIT #3: nam='db file sequential read' ela= 10630 file#=2 block#=589 blocks=1 obj#=0 tim=8736698999
EXEC #3:c=0,e=26592,p=3,cr=1,cu=10,mis=0,r=1,dep=0,og=1,tim=8736699155
WAIT #3: nam='SQL*Net message to client' ela= 5 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736699226
WAIT #3: nam='SQL*Net message from client' ela= 15983 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736715245
=====================
PARSING IN CURSOR #4 len=21 dep=0 uid=54 ct=3 lid=54 tim=8736715415 hv=2564406738 ad='2f614370'
select 'x' from dual
END OF STMT
PARSE #4:c=0,e=24,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736715408
BINDS #4:
EXEC #4:c=0,e=38,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736715500
WAIT #4: nam='SQL*Net message to client' ela= 5 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736715534
WAIT #4: nam='SQL*Net message from client' ela= 258 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736715839
WAIT #4: nam='SQL*Net message to client' ela= 3 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736715916
FETCH #4:c=0,e=48,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=8736715940
WAIT #4: nam='SQL*Net message from client' ela= 7220 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736723217
STAT #4 id=1 cnt=1 pid=0 pos=1 bj=0 p='FAST DUAL (cr=0 pr=0 pw=0 time=13 us)'
=====================
PARSING IN CURSOR #6 len=61 dep=0 uid=54 ct=47 lid=54 tim=8736723415 hv=356401299 ad='2f727528'
begin :id := sys.dbms_transaction.local_transaction_id; end;
END OF STMT
PARSE #6:c=0,e=44,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736723411
BINDS #6:
kkscoacd
Bind#0
acdty=01 mxl=2000(2000) mxlc=00 mal=00 scl=00 pre=00
acflg=01 fl2=1000000 frm=01 csi=852 siz=2000 ff=0
kxsbbbfp=0d70c298 bln=2000 avl=00 flg=05
WAIT #6: nam='SQL*Net message to client' ela= 5 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736723622
EXEC #6:c=0,e=170,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=8736723647
WAIT #6: nam='SQL*Net message from client' ela= 298 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736724009
PARSE #5:c=0,e=15,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=8736724137
XCTEND rlbk=0, rd_only=0
EXEC #5:c=0,e=119,p=0,cr=0,cu=1,mis=0,r=0,dep=0,og=0,tim=8736724281
WAIT #5: nam='log file sync' ela= 1246 buffer#=5365 p2=0 p3=0 obj#=0 tim=8736725562
WAIT #5: nam='SQL*Net message to client' ela= 4 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736725631
WAIT #5: nam='SQL*Net message from client' ela= 16025 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736741693
=====================
PARSING IN CURSOR #3 len=21 dep=0 uid=54 ct=3 lid=54 tim=8736741837 hv=2564406738 ad='2f614370'
select 'x' from dual
END OF STMT
PARSE #3:c=0,e=36,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736741832
BINDS #3:
EXEC #3:c=0,e=50,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736741934
WAIT #3: nam='SQL*Net message to client' ela= 4 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736741966
WAIT #3: nam='SQL*Net message from client' ela= 211 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736742219
WAIT #3: nam='SQL*Net message to client' ela= 3 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736742290
FETCH #3:c=0,e=48,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=8736742312
WAIT #3: nam='SQL*Net message from client' ela= 7338 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736749705
STAT #3 id=1 cnt=1 pid=0 pos=1 bj=0 p='FAST DUAL (cr=0 pr=0 pw=0 time=11 us)'
=====================
PARSING IN CURSOR #4 len=61 dep=0 uid=54 ct=47 lid=54 tim=8736749928 hv=356401299 ad='2f727528'
begin :id := sys.dbms_transaction.local_transaction_id; end;
END OF STMT
PARSE #4:c=0,e=45,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736749924
BINDS #4:
kkscoacd
Bind#0
acdty=01 mxl=2000(2000) mxlc=00 mal=00 scl=00 pre=00
acflg=01 fl2=1000000 frm=01 csi=852 siz=2000 ff=0
kxsbbbfp=0d70c298 bln=2000 avl=00 flg=05
WAIT #4: nam='SQL*Net message to client' ela= 5 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736750125
EXEC #4:c=0,e=158,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=8736750149
WAIT #4: nam='SQL*Net message from client' ela= 309 driver id=1413697536 #bytes=1 p3=0 obj#=0 tim=8736750514
=====================
PARSING IN CURSOR #6 len=57 dep=0 uid=54 ct=42 lid=54 tim=8736750880 hv=312686975 ad='2f5fce78'
alter session set events '10046 trace name context off'
END OF STMT
PARSE #6:c=0,e=251,p=0,cr=0,cu=0,mis=1,r=0,dep=0,og=1,tim=8736750875
BINDS #6:
EXEC #6:c=0,e=73,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=8736751008

+++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++

10046的跟踪文件内容太多，可以使用tkprof工具进行整理转化：

D:\Documents and Settings\tian>tkprof D:\admin\test\udump\test_ora_6488.trc c:\6
488.txt

TKPROF: Release 10.2.0.1.0 - Production on 星期日 7月 7 12:00:24 2013

得到的文件内容为：

TKPROF: Release 10.2.0.1.0 - Production on 星期日 7月 7 12:00:24 2013

Trace file: D:\admin\test\udump\test_ora_6488.trc
Sort options: default

********************************************************************************
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
********************************************************************************

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

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        0      0.00       0.00          0          0          0           0
Execute      1      0.00       0.00          0          0          0           0
Fetch        0      0.00       0.00          0          0          0           0
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total        1      0.00       0.00          0          0          0           0

Misses in library cache during parse: 0
Misses in library cache during execute: 1
Optimizer mode: ALL_ROWS
Parsing user id: 54

Elapsed times include waiting on following events:
Event waited on                             Times   Max. Wait Total Waited
----------------------------------------   Waited ---------- ------------
SQL*Net message to client                       1        0.00          0.00
SQL*Net message from client                     1        0.01          0.01
********************************************************************************

select 'x'
from
dual

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        7      0.00       0.00          0          0          0           0
Execute      7      0.00       0.00          0          0          0           0
Fetch        7      0.00       0.00          0          0          0           7
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total       21      0.00       0.00          0          0          0           7

Misses in library cache during parse: 1
Optimizer mode: ALL_ROWS
Parsing user id: 54

Rows Row Source Operation
------- ---------------------------------------------------
1 FAST DUAL (cr=0 pr=0 pw=0 time=13 us)

Elapsed times include waiting on following events:
Event waited on                             Times   Max. Wait Total Waited
----------------------------------------   Waited ---------- ------------
SQL*Net message to client                      14        0.00          0.00
SQL*Net message from client                    14        0.00          0.05
********************************************************************************

begin :id := sys.dbms_transaction.local_transaction_id; end;

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        9      0.00       0.00          0          0          0           0
Execute      9      0.01       0.00          0          0          1           8
Fetch        0      0.00       0.00          0          0          0           0
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total       18      0.01       0.00          0          0          1           8

Misses in library cache during parse: 0
Optimizer mode: ALL_ROWS
Parsing user id: 54

Elapsed times include waiting on following events:
Event waited on                             Times   Max. Wait Total Waited
----------------------------------------   Waited ---------- ------------
SQL*Net message to client                       9        0.00          0.00
SQL*Net message from client                     9        0.01          0.03
log file sync                                   1        0.00          0.00
********************************************************************************

select obj#,type#,ctime,mtime,stime,status,dataobj#,flags,oid$, spare1,
spare2
from
obj$ where owner#=:1 and name=:2 and namespace=:3 and remoteowner is null
and linkname is null and subname is null

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        1      0.01       0.00          0          0          0           0
Execute      2      0.00       0.00          0          0          0           0
Fetch        2      0.00       0.00          0          6          0           2
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total        5      0.01       0.00          0          6          0           2

Misses in library cache during parse: 1
Misses in library cache during execute: 1
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)

Rows     Row Source Operation
------- ---------------------------------------------------
      1 TABLE ACCESS BY INDEX ROWID OBJ$ (cr=3 pr=0 pw=0 time=58 us)
      1   INDEX RANGE SCAN I_OBJ2 (cr=2 pr=0 pw=0 time=31 us)(object id 37)

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

select t.ts#,t.file#,t.block#,nvl(t.bobj#,0),nvl(t.tab#,0),t.intcols,
nvl(t.clucols,0),t.audit$,t.flags,t.pctfree$,t.pctused$,t.initrans,
t.maxtrans,t.rowcnt,t.blkcnt,t.empcnt,t.avgspc,t.chncnt,t.avgrln,
t.analyzetime,t.samplesize,t.cols,t.property,nvl(t.degree,1),
nvl(t.instances,1),t.avgspc_flb,t.flbcnt,t.kernelcols,nvl(t.trigflag, 0),
nvl(t.spare1,0),nvl(t.spare2,0),t.spare4,t.spare6,ts.cachedblk,ts.cachehit,
ts.logicalread
from
tab$ t, tab_stats$ ts where t.obj#= :1 and t.obj# = ts.obj# (+)

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        1      0.00       0.00          0          0          0           0
Execute      2      0.00       0.00          0          0          0           0
Fetch        2      0.00       0.00          0          8          0           2
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total        5      0.00       0.00          0          8          0           2

Misses in library cache during parse: 1
Misses in library cache during execute: 1
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)

Rows     Row Source Operation
------- ---------------------------------------------------
      1 NESTED LOOPS OUTER (cr=4 pr=0 pw=0 time=109 us)
      1   TABLE ACCESS CLUSTER TAB$ (cr=3 pr=0 pw=0 time=64 us)
      1    INDEX UNIQUE SCAN I_OBJ# (cr=2 pr=0 pw=0 time=27 us)(object id 3)
      0   TABLE ACCESS BY INDEX ROWID TAB_STATS$ (cr=1 pr=0 pw=0 time=24 us)
      0    INDEX UNIQUE SCAN I_TAB_STATS$_OBJ# (cr=1 pr=0 pw=0 time=11 us)(object id 709)

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

select i.obj#,i.ts#,i.file#,i.block#,i.intcols,i.type#,i.flags,i.property,
i.pctfree$,i.initrans,i.maxtrans,i.blevel,i.leafcnt,i.distkey,i.lblkkey,
i.dblkkey,i.clufac,i.cols,i.analyzetime,i.samplesize,i.dataobj#,
nvl(i.degree,1),nvl(i.instances,1),i.rowcnt,mod(i.pctthres$,256),
i.indmethod#,i.trunccnt,nvl(c.unicols,0),nvl(c.deferrable#+c.valid#,0),
nvl(i.spare1,i.intcols),i.spare4,i.spare2,i.spare6,decode(i.pctthres$,null,
null,mod(trunc(i.pctthres$/256),256)),ist.cachedblk,ist.cachehit,
ist.logicalread
from
ind$ i, ind_stats$ ist, (select enabled, min(cols) unicols,
min(to_number(bitand(defer,1))) deferrable#,min(to_number(bitand(defer,4)))
valid# from cdef$ where obj#=:1 and enabled > 1 group by enabled) c where
i.obj#=c.enabled(+) and i.obj# = ist.obj#(+) and i.bo#=:1 order by i.obj#

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        1      0.00       0.00          0          0          0           0
Execute      2      0.01       0.00          0          0          0           0
Fetch        5      0.01       0.02          2         18          0           3
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total        8      0.03       0.02          2         18          0           3

Misses in library cache during parse: 1
Misses in library cache during execute: 1
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)

Rows     Row Source Operation
------- ---------------------------------------------------
      2 SORT ORDER BY (cr=10 pr=2 pw=0 time=18560 us)
      2   HASH JOIN OUTER (cr=10 pr=2 pw=0 time=17051 us)
      2    NESTED LOOPS OUTER (cr=7 pr=0 pw=0 time=159 us)
      2     TABLE ACCESS CLUSTER IND$ (cr=5 pr=0 pw=0 time=78 us)
      1      INDEX UNIQUE SCAN I_OBJ# (cr=2 pr=0 pw=0 time=28 us)(object id 3)
      0     TABLE ACCESS BY INDEX ROWID IND_STATS$ (cr=2 pr=0 pw=0 time=54 us)
      0      INDEX UNIQUE SCAN I_IND_STATS$_OBJ# (cr=2 pr=0 pw=0 time=25 us)(object id 711)
      1    VIEW (cr=3 pr=2 pw=0 time=14749 us)
      1     SORT GROUP BY (cr=3 pr=2 pw=0 time=14730 us)
      1      TABLE ACCESS BY INDEX ROWID CDEF$ (cr=3 pr=2 pw=0 time=14617 us)
      2       INDEX RANGE SCAN I_CDEF2 (cr=2 pr=1 pw=0 time=3126 us)(object id 51)

Elapsed times include waiting on following events:
Event waited on                             Times   Max. Wait Total Waited
----------------------------------------   Waited ---------- ------------
db file sequential read                         2        0.01          0.01
********************************************************************************

select pos#,intcol#,col#,spare1,bo#,spare2
from
icol$ where obj#=:1

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        1      0.00       0.00          0          0          0           0
Execute      3      0.00       0.00          0          0          0           0
Fetch        6      0.00       0.00          1         12          0           3
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total       10      0.00       0.00          1         12          0           3

Misses in library cache during parse: 1
Misses in library cache during execute: 1
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)

Rows     Row Source Operation
------- ---------------------------------------------------
      1 TABLE ACCESS BY INDEX ROWID ICOL$ (cr=4 pr=0 pw=0 time=71 us)
      1   INDEX RANGE SCAN I_ICOL1 (cr=3 pr=0 pw=0 time=41 us)(object id 40)

Elapsed times include waiting on following events:
Event waited on                             Times   Max. Wait Total Waited
----------------------------------------   Waited ---------- ------------
db file sequential read                         1        0.00          0.00
********************************************************************************

select name,intcol#,segcol#,type#,length,nvl(precision#,0),decode(type#,2,
nvl(scale,-127/*MAXSB1MINAL*/),178,scale,179,scale,180,scale,181,scale,182,
scale,183,scale,231,scale,0),null$,fixedstorage,nvl(deflength,0),default$,
rowid,col#,property, nvl(charsetid,0),nvl(charsetform,0),spare1,spare2,
nvl(spare3,0)
from
col$ where obj#=:1 order by intcol#

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        1      0.00       0.00          0          0          0           0
Execute      2      0.00       0.00          0          0          0           0
Fetch       13      0.00       0.00          0          6          0          11
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total       16      0.00       0.00          0          6          0          11

Misses in library cache during parse: 1
Misses in library cache during execute: 1
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)

Rows     Row Source Operation
------- ---------------------------------------------------
      8 SORT ORDER BY (cr=3 pr=0 pw=0 time=113 us)
      8   TABLE ACCESS CLUSTER COL$ (cr=3 pr=0 pw=0 time=78 us)
      1    INDEX UNIQUE SCAN I_OBJ# (cr=2 pr=0 pw=0 time=22 us)(object id 3)

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

select type#,blocks,extents,minexts,maxexts,extsize,extpct,user#,iniexts,
NVL(lists,65535),NVL(groups,65535),cachehint,hwmincr, NVL(spare1,0),
NVL(scanhint,0)
from
seg$ where ts#=:1 and file#=:2 and block#=:3

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        5      0.00       0.00          0          0          0           0
Execute      5      0.00       0.00          0          0          0           0
Fetch        5      0.00       0.01          3         15          0           5
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total       15      0.00       0.01          3         15          0           5

Misses in library cache during parse: 1
Misses in library cache during execute: 1
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)

Rows     Row Source Operation
------- ---------------------------------------------------
      1 TABLE ACCESS CLUSTER SEG$ (cr=3 pr=2 pw=0 time=1074 us)
      1   INDEX UNIQUE SCAN I_FILE#_BLOCK# (cr=2 pr=1 pw=0 time=416 us)(object id 9)

Elapsed times include waiting on following events:
Event waited on                             Times   Max. Wait Total Waited
----------------------------------------   Waited ---------- ------------
db file sequential read                         3        0.00          0.01
********************************************************************************

select o.owner#,o.name,o.namespace,o.remoteowner,o.linkname,o.subname,
o.dataobj#,o.flags
from
obj$ o where o.obj#=:1

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        3      0.00       0.00          0          0          0           0
Execute      5      0.01       0.00          0          0          0           0
Fetch        5      0.00       0.00          0         15          0           5
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total       13      0.01       0.00          0         15          0           5

Misses in library cache during parse: 1
Misses in library cache during execute: 1
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)

Rows     Row Source Operation
------- ---------------------------------------------------
      1 TABLE ACCESS BY INDEX ROWID OBJ$ (cr=3 pr=0 pw=0 time=52 us)
      1   INDEX UNIQUE SCAN I_OBJ1 (cr=2 pr=0 pw=0 time=24 us)(object id 36)

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

select col#, grantee#, privilege#,max(mod(nvl(option$,0),2))
from
objauth$ where obj#=:1 and col# is not null group by privilege#, col#,
grantee# order by col#, grantee#

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        1      0.00       0.00          0          0          0           0
Execute      2      0.00       0.00          0          0          0           0
Fetch        2      0.00       0.00          0          4          0           0
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total        5      0.00       0.00          0          4          0           0

Misses in library cache during parse: 1
Misses in library cache during execute: 1
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)

Rows     Row Source Operation
------- ---------------------------------------------------
      0 SORT GROUP BY (cr=2 pr=0 pw=0 time=143 us)
      0   TABLE ACCESS BY INDEX ROWID OBJAUTH$ (cr=2 pr=0 pw=0 time=105 us)
      0    INDEX RANGE SCAN I_OBJAUTH1 (cr=2 pr=0 pw=0 time=48 us)(object id 103)

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

select grantee#,privilege#,nvl(col#,0),max(mod(nvl(option$,0),2))
from
objauth$ where obj#=:1 group by grantee#,privilege#,nvl(col#,0) order by
grantee#

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        1      0.00       0.00          0          0          0           0
Execute      2      0.00       0.00          0          0          0           0
Fetch        2      0.00       0.00          0          4          0           0
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total        5      0.00       0.00          0          4          0           0

Misses in library cache during parse: 1
Misses in library cache during execute: 1
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)

Rows     Row Source Operation
------- ---------------------------------------------------
      0 SORT GROUP BY (cr=2 pr=0 pw=0 time=71 us)
      0   TABLE ACCESS BY INDEX ROWID OBJAUTH$ (cr=2 pr=0 pw=0 time=40 us)
      0    INDEX RANGE SCAN I_OBJAUTH1 (cr=2 pr=0 pw=0 time=26 us)(object id 103)

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

select con#,obj#,rcon#,enabled,nvl(defer,0)
from
cdef$ where robj#=:1

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        1      0.00       0.00          0          0          0           0
Execute      2      0.00       0.00          0          0          0           0
Fetch        3      0.00       0.00          0          4          0           1
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total        6      0.00       0.00          0          4          0           1

Misses in library cache during parse: 1
Misses in library cache during execute: 1
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)

Rows     Row Source Operation
------- ---------------------------------------------------
      0 TABLE ACCESS BY INDEX ROWID CDEF$ (cr=1 pr=0 pw=0 time=36 us)
      0   INDEX RANGE SCAN I_CDEF3 (cr=1 pr=0 pw=0 time=21 us)(object id 52)

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

select con#,type#,condlength,intcols,robj#,rcon#,match#,refact,nvl(enabled,0),
rowid,cols,nvl(defer,0),mtime,nvl(spare1,0)
from
cdef$ where obj#=:1

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        1      0.00       0.00          0          0          0           0
Execute      2      0.00       0.00          0          0          0           0
Fetch        5      0.00       0.00          0         10          0           3
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total        8      0.00       0.00          0         10          0           3

Misses in library cache during parse: 1
Misses in library cache during execute: 1
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)

Rows     Row Source Operation
------- ---------------------------------------------------
      2 TABLE ACCESS BY INDEX ROWID CDEF$ (cr=6 pr=0 pw=0 time=54 us)
      2   INDEX RANGE SCAN I_CDEF2 (cr=4 pr=0 pw=0 time=37 us)(object id 51)

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

select intcol#,nvl(pos#,0),col#,nvl(spare1,0)
from
ccol$ where con#=:1

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        1      0.00       0.00          0          0          0           0
Execute      3      0.00       0.00          0          0          0           0
Fetch        6      0.01       0.01          1         12          0           3
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total       10      0.01       0.01          1         12          0           3

Misses in library cache during parse: 1
Misses in library cache during execute: 1
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)

Rows     Row Source Operation
------- ---------------------------------------------------
      1 TABLE ACCESS BY INDEX ROWID CCOL$ (cr=4 pr=1 pw=0 time=13754 us)
      1   INDEX RANGE SCAN I_CCOL1 (cr=3 pr=1 pw=0 time=13754 us)(object id 54)

Elapsed times include waiting on following events:
Event waited on                             Times   Max. Wait Total Waited
----------------------------------------   Waited ---------- ------------
db file sequential read                         1        0.01          0.01
********************************************************************************

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        3      0.00       0.00          0          0          0           0
Execute     11      0.00       0.00          0          0          0           0
Fetch       11      0.00       0.00          2         33          0          11
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total       25      0.00       0.00          2         33          0          11

Misses in library cache during parse: 1
Misses in library cache during execute: 1
Optimizer mode: RULE
Parsing user id: SYS (recursive depth: 1)

Rows     Row Source Operation
------- ---------------------------------------------------
      1 TABLE ACCESS BY INDEX ROWID HIST_HEAD$ (cr=3 pr=2 pw=0 time=869 us)
      1   INDEX RANGE SCAN I_HH_OBJ#_INTCOL# (cr=2 pr=1 pw=0 time=471 us)(object id 257)

Elapsed times include waiting on following events:
Event waited on                             Times   Max. Wait Total Waited
----------------------------------------   Waited ---------- ------------
db file sequential read                         2        0.00          0.00
********************************************************************************

select /*+ rule */ bucket, endpoint, col#, epvalue
from
histgrm$ where obj#=:1 and intcol#=:2 and row#=:3 order by bucket

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        1      0.00       0.00          0          0          0           0
Execute      2      0.00       0.00          0          0          0           0
Fetch        2      0.00       0.01          3          6          0           6
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total        5      0.00       0.02          3          6          0           6

Misses in library cache during parse: 1
Misses in library cache during execute: 1
Optimizer mode: RULE
Parsing user id: SYS (recursive depth: 1)

Rows     Row Source Operation
------- ---------------------------------------------------
      2 SORT ORDER BY (cr=3 pr=2 pw=0 time=15404 us)
      2   TABLE ACCESS CLUSTER HISTGRM$ (cr=3 pr=2 pw=0 time=15342 us)
      1    INDEX UNIQUE SCAN I_OBJ#_INTCOL# (cr=2 pr=1 pw=0 time=6172 us)(object id 252)

Elapsed times include waiting on following events:
Event waited on                             Times   Max. Wait Total Waited
----------------------------------------   Waited ---------- ------------
db file sequential read                         3        0.00          0.01
********************************************************************************

select job,dname
from
dept,emp where dept.deptno=emp.deptno

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        1      0.00       0.01          0          0          0           0
Execute      1      0.00       0.00          0          0          0           0
Fetch        1      0.00       0.01         15         16          0           7
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total        3      0.00       0.03         15         16          0           7

Misses in library cache during parse: 1
Optimizer mode: ALL_ROWS
Parsing user id: 54

Rows     Row Source Operation
------- ---------------------------------------------------
      7 NESTED LOOPS (cr=16 pr=15 pw=0 time=18324 us)
      7   TABLE ACCESS FULL EMP (cr=7 pr=6 pw=0 time=15311 us)
      7   TABLE ACCESS BY INDEX ROWID DEPT (cr=9 pr=9 pw=0 time=2846 us)
      7    INDEX UNIQUE SCAN PK_DEPT (cr=2 pr=1 pw=0 time=2086 us)(object id 51150)

Elapsed times include waiting on following events:
Event waited on                             Times   Max. Wait Total Waited
----------------------------------------   Waited ---------- ------------
SQL*Net message to client                       2        0.00          0.00
SQL*Net message from client                     2        0.00          0.00
db file sequential read                         2        0.01          0.01
db file scattered read                          2        0.00          0.00
********************************************************************************

select u.name,o.name, t.update$, t.insert$, t.delete$, t.enabled
from
obj$ o,user$ u,trigger$ t where t.baseobject=:1 and t.obj#=o.obj# and
o.owner#=u.user# and bitand(property,16)=0 and bitand(property,8)=0 order
by o.obj#

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

Misses in library cache during parse: 1
Misses in library cache during execute: 1
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)

Rows     Row Source Operation
------- ---------------------------------------------------
      0 SORT ORDER BY (cr=1 pr=0 pw=0 time=105 us)
      0   NESTED LOOPS (cr=1 pr=0 pw=0 time=80 us)
      0    NESTED LOOPS (cr=1 pr=0 pw=0 time=69 us)
      0     TABLE ACCESS BY INDEX ROWID TRIGGER$ (cr=1 pr=0 pw=0 time=56 us)
      0      INDEX RANGE SCAN I_TRIGGER1 (cr=1 pr=0 pw=0 time=22 us)(object id 125)
      0     TABLE ACCESS BY INDEX ROWID OBJ$ (cr=0 pr=0 pw=0 time=0 us)
      0      INDEX UNIQUE SCAN I_OBJ1 (cr=0 pr=0 pw=0 time=0 us)(object id 36)
      0    TABLE ACCESS CLUSTER USER$ (cr=0 pr=0 pw=0 time=0 us)
      0     INDEX UNIQUE SCAN I_USER# (cr=0 pr=0 pw=0 time=0 us)(object id 11)

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

update emp set ename=ename||'a'

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        1      0.00       0.00          0          0          0           0
Execute      1      0.00       0.02          2          7         16           7
Fetch        0      0.00       0.00          0          0          0           0
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total        2      0.00       0.02          2          7         16           7

Misses in library cache during parse: 1
Optimizer mode: ALL_ROWS
Parsing user id: 54

Rows     Row Source Operation
------- ---------------------------------------------------
      0 UPDATE EMP (cr=7 pr=2 pw=0 time=23891 us)
      7   TABLE ACCESS FULL EMP (cr=7 pr=0 pw=0 time=92 us)

Elapsed times include waiting on following events:
Event waited on                             Times   Max. Wait Total Waited
----------------------------------------   Waited ---------- ------------
db file sequential read                         2        0.01          0.02
SQL*Net message to client                       1        0.00          0.00
SQL*Net message from client                     1        0.01          0.01
********************************************************************************

select parttype, partcnt, partkeycols, flags, defts#, defpctfree, defpctused,
definitrans, defmaxtrans, deftiniexts, defextsize, defminexts, defmaxexts,
defextpct, deflists, defgroups, deflogging, spare1, mod(spare2, 256)
subparttype, mod(trunc(spare2/256), 256) subpartkeycols,
mod(trunc(spare2/65536), 65536) defsubpartcnt, mod(trunc(spare2/4294967296),
256) defhscflags
from
partobj$ where obj# = :1

Misses in library cache during parse: 1
Misses in library cache during execute: 1
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)

Rows     Row Source Operation
------- ---------------------------------------------------
      0 TABLE ACCESS BY INDEX ROWID PARTOBJ$ (cr=1 pr=0 pw=0 time=28 us)
      0   INDEX UNIQUE SCAN I_PARTOBJ$ (cr=1 pr=0 pw=0 time=13 us)(object id 263)

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

delete from emp
where
empno in (select empno from dept where dept.dname='RESEARCH')

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        1      0.01       0.00          0          0          0           0
Execute      1      0.00       0.02          4         56         36           7
Fetch        0      0.00       0.00          0          0          0           0
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total        2      0.01       0.02          4         56         36           7

Misses in library cache during parse: 1
Optimizer mode: ALL_ROWS
Parsing user id: 54

Rows     Row Source Operation
------- ---------------------------------------------------
      0 DELETE EMP (cr=56 pr=4 pw=0 time=21863 us)
      7   FILTER (cr=56 pr=1 pw=0 time=1082 us)
      7    TABLE ACCESS FULL EMP (cr=7 pr=0 pw=0 time=88 us)
      7    FILTER (cr=49 pr=1 pw=0 time=714 us)
      7     TABLE ACCESS FULL DEPT (cr=49 pr=1 pw=0 time=604 us)

Elapsed times include waiting on following events:
Event waited on                             Times   Max. Wait Total Waited
----------------------------------------   Waited ---------- ------------
db file sequential read                         4        0.01          0.02
SQL*Net message to client                       1        0.00          0.00
SQL*Net message from client                     1        0.01          0.01
********************************************************************************

commit

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          1           0
Fetch        0      0.00       0.00          0          0          0           0
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total        2      0.00       0.00          0          0          1           0

Misses in library cache during parse: 0
Parsing user id: 54

Elapsed times include waiting on following events:
Event waited on                             Times   Max. Wait Total Waited
----------------------------------------   Waited ---------- ------------
log file sync                                   1        0.00          0.00
SQL*Net message to client                       1        0.00          0.00
SQL*Net message from client                     1        0.02          0.02
********************************************************************************

insert into EMP (EMPNO, ENAME, JOB, MGR, HIREDATE, SAL, COMM, DEPTNO)
values (7876, 'ADAMS', 'CLERK', 7788, to_date('23-05-1987', 'dd-mm-yyyy'), 1100, null, 20)

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse        1      0.00       0.00          0          0          0           0
Execute      1      0.00       0.02          3          1         10           1
Fetch        0      0.00       0.00          0          0          0           0
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total        2      0.00       0.02          3          1         10           1

Misses in library cache during parse: 1
Optimizer mode: ALL_ROWS
Parsing user id: 54

Elapsed times include waiting on following events:
Event waited on                             Times   Max. Wait Total Waited
----------------------------------------   Waited ---------- ------------
db file sequential read                         3        0.01          0.02
SQL*Net message to client                       1        0.00          0.00
SQL*Net message from client                     1        0.01          0.01
********************************************************************************

alter session set events '10046 trace name context off'

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        0      0.00       0.00          0          0          0           0
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total        2      0.00       0.00          0          0          0           0

Misses in library cache during parse: 1
Optimizer mode: ALL_ROWS
Parsing user id: 54

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

OVERALL TOTALS FOR ALL NON-RECURSIVE STATEMENTS

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse       22      0.01       0.02          0          0          0           0
Execute     23      0.01       0.07          9         64         64          23
Fetch        8      0.00       0.01         15         16          0          14
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total       53      0.03       0.11         24         80         64          37

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

Elapsed times include waiting on following events:
Event waited on                             Times   Max. Wait Total Waited
----------------------------------------   Waited ---------- ------------
SQL*Net message to client                      30        0.00          0.00
SQL*Net message from client                    30        0.02          0.17
db file sequential read                        11        0.01          0.08
db file scattered read                          2        0.00          0.00
log file sync                                   2        0.00          0.00

OVERALL TOTALS FOR ALL RECURSIVE STATEMENTS

call     count       cpu    elapsed       disk      query    current        rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse       24      0.01       0.01          0          0          0           0
Execute     47      0.03       0.04          0          0          0           0
Fetch       71      0.03       0.07         12        155          0          55
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total      142      0.07       0.13         12        155          0          55

Misses in library cache during parse: 16
Misses in library cache during execute: 16

   22 user SQL statements in session.
   47 internal SQL statements in session.
   69 SQL statements in session.
********************************************************************************
Trace file: D:\admin\test\udump\test_ora_6488.trc
Trace file compatibility: 10.01.00
Sort options: default

       1 session in tracefile.
      22 user SQL statements in trace file.
      47 internal SQL statements in trace file.
      69 SQL statements in trace file.
      25 unique SQL statements in trace file.
    1233 lines in trace file.
       0 elapsed seconds in trace file.

+++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++

通过10046事件，我们可以看到执行一条语句时，oracle内部进行了哪些操作，时间成本如何，是否发生等待以及等待时间。
但在选择执行计划时，我们看不到选择了什么（这需要10053事件），只能看到oracle对内部表（含$符号的）进行重复操作。
用tkprof处理过的10046事件跟踪文件，是对各个语句的解析、执行、提取（parse、execute、fetch）、执行计划和等待时间的统计。

简单地说，10046事件能告诉我们oracle做了什么，而10053事件告诉我们oracle为什么这样做（为什么选择此方法而不是其他方法）

0 0