获取10046 trace的方法
sys@ORCL>oradebug setmypid
已处理的语句
sys@ORCL>oradebug event 10046 trace name context forever,level 12;
已处理的语句
sys@ORCL>select * from t1;
ID
----------
4
sys@ORCL>oradebug event 10046 trace name context off;
已处理的语句
sys@ORCL>oradebug tracefile_name;
/u01/app/oracle/diag/rdbms/orcl/orcl/trace/orcl_ora_9522.trc
orcl_ora_9522.trc就是本次的10046 trace,可以通过linux命令more或者cat直接查看,也可以用tkprof 命令翻译 trace 文件
直接查看
[oracle@hisdb u01]$ more /u01/app/oracle/diag/rdbms/orcl/orcl/trace/orcl_ora_9522.trc
Trace file /u01/app/oracle/diag/rdbms/orcl/orcl/trace/orcl_ora_9522.trc
Oracle Database 11g Enterprise Edition Release 11.2.0.4.0 - 64bit Production
With the Partitioning, Oracle Label Security, OLAP, Data Mining,
Oracle Database Vault and Real Application Testing options
ORACLE_HOME = /u01/app/oracle/product/11.2.0/db_1
System name: Linux
Node name: hisdb
Release: 3.10.0-514.el7.x86_64
Version: #1 SMP Tue Nov 22 16:42:41 UTC 2016
Machine: x86_64
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 34
Unix process pid: 9522, image: oracle@hisdb (TNS V1-V3)
*** 2022-12-19 10:55:59.398
*** SESSION ID:(57.67) 2022-12-19 10:55:59.398
*** CLIENT ID:() 2022-12-19 10:55:59.398
*** SERVICE NAME:(SYS$USERS) 2022-12-19 10:55:59.398
*** MODULE NAME:(sqlplus@hisdb (TNS V1-V3)) 2022-12-19 10:55:59.398
*** ACTION NAME:() 2022-12-19 10:55:59.398
Processing Oradebug command 'setmypid'
*** 2022-12-19 10:55:59.398
Oradebug command 'setmypid' console output: <none>
*** 2022-12-19 10:56:20.501
Processing Oradebug command 'event 10046 trace name context forever,level 12'
*** 2022-12-19 10:56:20.502
Oradebug command 'event 10046 trace name context forever,level 12' console output: <none>
WAIT #0: nam='SQL*Net message to client' ela= 2 driver id=1650815232 #bytes=1 p3=0 obj#=-1 tim=1671418580502452
*** 2022-12-19 10:56:26.637
WAIT #0: nam='SQL*Net message from client' ela= 6134537 driver id=1650815232 #bytes=1 p3=0 obj#=-1 tim=1671418586637062
=====================
PARSING IN CURSOR #140505982140776 len=202 dep=1 uid=0 oct=3 lid=0 tim=1671418586638528 hv=3819099649 ad='87bfcbd0' sqlid='3nkd3g3ju5ph1'
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 n
ull and subname is null
END OF STMT
BINDS #140505982140776:
Bind#0
oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
oacflg=00 fl2=0001 frm=00 csi=00 siz=80 off=0
kxsbbbfp=7fca19293a78 bln=22 avl=01 flg=05
value=0
Bind#1
oacdty=01 mxl=32(02) mxlc=00 mal=00 scl=00 pre=00
oacflg=10 fl2=0001 frm=01 csi=852 siz=0 off=24
kxsbbbfp=7fca19293a90 bln=32 avl=02 flg=01
value="T1"
Bind#2
oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
oacflg=00 fl2=0001 frm=00 csi=00 siz=0 off=56
kxsbbbfp=7fca19293ab0 bln=22 avl=02 flg=01
value=1
EXEC #140505982140776:c=2027,e=602932,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=2853959010,tim=1671418587240249
FETCH #140505982140776:c=49,e=48,p=0,cr=4,cu=0,mis=0,r=1,dep=1,og=4,plh=2853959010,tim=1671418587240320
STAT #140505982140776 id=1 cnt=1 pid=0 pos=1 obj=18 op='TABLE ACCESS BY INDEX ROWID OBJ$ (cr=4 pr=0 pw=0 time=47 us cost=4 size=84 card=1)'
STAT #140505982140776 id=2 cnt=1 pid=1 pos=1 obj=37 op='INDEX RANGE SCAN I_OBJ2 (cr=3 pr=0 pw=0 time=27 us cost=3 size=0 card=1)'
CLOSE #140505982140776:c=46,e=48,dep=1,type=3,tim=1671418587240501
=====================
PARSING IN CURSOR #140505982106416 len=493 dep=1 uid=0 oct=3 lid=0 tim=1671418587240571 hv=2584065658 ad='87bdc008' sqlid='1gu8t96d0bdmu'
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.avg
spc,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.s
pare2,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
BINDS #140505982106416:
Bind#0
oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
oacflg=00 fl2=0001 frm=00 csi=00 siz=24 off=0
kxsbbbfp=7fca1926ee78 bln=22 avl=04 flg=05
value=88231
EXEC #140505982106416:c=58,e=58,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=3526770254,tim=1671418587240614
FETCH #140505982106416:c=33,e=33,p=0,cr=4,cu=0,mis=0,r=1,dep=1,og=4,plh=3526770254,tim=1671418587240656
CLOSE #140505982106416:c=3,e=3,dep=1,type=3,tim=1671418587240672
=====================
PARSING IN CURSOR #140505983008264 len=789 dep=1 uid=0 oct=3 lid=0 tim=1671418587240783 hv=3159716790 ad='87bdaa30' sqlid='7ng34ruy5awxq'
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.analy
zetime,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, (selec
t 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
BINDS #140505983008264:
Bind#0
oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
oacflg=00 fl2=0001 frm=00 csi=00 siz=24 off=0
kxsbbbfp=7fca1926ee78 bln=22 avl=04 flg=05
value=88231
Bind#1
No oacdef for this bind.
EXEC #140505983008264:c=0,e=77,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=3992920156,tim=1671418587240844
FETCH #140505983008264:c=0,e=145,p=0,cr=3,cu=0,mis=0,r=0,dep=1,og=4,plh=3992920156,tim=1671418587240996
CLOSE #140505983008264:c=0,e=1,dep=1,type=3,tim=1671418587241020
=====================
PARSING IN CURSOR #140505982112296 len=348 dep=1 uid=0 oct=3 lid=0 tim=1671418587241040 hv=2512561537 ad='87bf9570' sqlid='83taa7kaw59c1'
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),n
ull$,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
BINDS #140505982112296:
Bind#0
oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
oacflg=00 fl2=0001 frm=00 csi=00 siz=24 off=0
kxsbbbfp=7fca1926ee78 bln=22 avl=04 flg=05
value=88231
EXEC #140505982112296:c=0,e=43,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=3765558045,tim=1671418587241075
FETCH #140505982112296:c=0,e=15,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,plh=3765558045,tim=1671418587241096
FETCH #140505982112296:c=0,e=3,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=3765558045,tim=1671418587241108
CLOSE #140505982112296:c=0,e=1,dep=1,type=3,tim=1671418587241116
=====================
PARSING IN CURSOR #140505982150160 len=210 dep=1 uid=0 oct=3 lid=0 tim=1671418587241279 hv=864012087 ad='87ad85b8' sqlid='96g93hntrzjtr'
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_hea
d$ where obj#=:1 and intcol#=:2
END OF STMT
BINDS #140505982150160:
Bind#0
oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
oacflg=00 fl2=0001 frm=00 csi=00 siz=48 off=0
kxsbbbfp=7fca1926ee60 bln=22 avl=04 flg=05
value=88231
Bind#1
oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
oacflg=00 fl2=0001 frm=00 csi=00 siz=0 off=24
kxsbbbfp=7fca1926ee78 bln=22 avl=02 flg=01
value=1
EXEC #140505982150160:c=0,e=63,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=3,plh=2239883476,tim=1671418587241328
FETCH #140505982150160:c=0,e=18,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=3,plh=2239883476,tim=1671418587241352
CLOSE #140505982150160:c=0,e=2,dep=1,type=3,tim=1671418587241362
=====================
PARSING IN CURSOR #140505981123976 len=16 dep=0 uid=0 oct=3 lid=0 tim=1671418587241508 hv=2245880055 ad='86a377c8' sqlid='27uhu2q2xuu7r'
select * from t1
END OF STMT
PARSE #140505981123976:c=3499,e=604393,p=0,cr=17,cu=0,mis=1,r=0,dep=0,og=1,plh=3617692013,tim=1671418587241507
EXEC #140505981123976:c=0,e=7,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=3617692013,tim=1671418587241540
WAIT #140505981123976: nam='SQL*Net message to client' ela= 1 driver id=1650815232 #bytes=1 p3=0 obj#=-1 tim=1671418587241556
FETCH #140505981123976:c=0,e=18,p=0,cr=2,cu=0,mis=0,r=1,dep=0,og=1,plh=3617692013,tim=1671418587241587
WAIT #140505981123976: nam='SQL*Net message from client' ela= 96 driver id=1650815232 #bytes=1 p3=0 obj#=-1 tim=1671418587241706
FETCH #140505981123976:c=0,e=5,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=3617692013,tim=1671418587241797
STAT #140505981123976 id=1 cnt=1 pid=0 pos=1 obj=88231 op='TABLE ACCESS FULL T1 (cr=2 pr=0 pw=0 time=15 us cost=2 size=3 card=1)'
WAIT #140505981123976: nam='SQL*Net message to client' ela= 1 driver id=1650815232 #bytes=1 p3=0 obj#=-1 tim=1671418587241838
WAIT #140505981123976: nam='SQL*Net message from client' ela= 189388 driver id=1650815232 #bytes=1 p3=0 obj#=-1 tim=1671418587431233
=====================
PARSING IN CURSOR #140505982248672 len=52 dep=0 uid=0 oct=47 lid=0 tim=1671418587431363 hv=1029988163 ad='87849498' sqlid='9babjv8yq8ru3'
BEGIN DBMS_OUTPUT.GET_LINES(:LINES, :NUMLINES); END;
END OF STMT
PARSE #140505982248672:c=0,e=83,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=0,tim=1671418587431362
BINDS #140505982248672:
Bind#0
oacdty=123 mxl=4000(4000) mxlc=00 mal=00 scl=00 pre=00
oacflg=00 fl2=1000000 frm=00 csi=00 siz=4000 off=0
toid ptr value=875947E0 length=16
E4B6D5CBDA2C1022E043ACAAE80A4927
kxsbbbfp=7fca19182d30 bln=4000 avl=00 flg=15
Bind#1
oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
oacflg=01 fl2=1000000 frm=00 csi=00 siz=24 off=0
kxsbbbfp=7fca19292298 bln=22 avl=22 flg=05
value=###
An invalid number has been seen.Memory contents are :
Dump of memory from 0x00007FCA19292298 to 0x00007FCA192922AE
7FCA19292290 000010C1 00000000 [........]
7FCA192922A0 00000000 00000000 00000000 00000000 [................]
WAIT #140505982248672: nam='SQL*Net message to client' ela= 1 driver id=1650815232 #bytes=1 p3=0 obj#=-1 tim=1671418587431549
EXEC #140505982248672:c=0,e=155,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,plh=0,tim=1671418587431564
*** 2022-12-19 10:56:38.785
WAIT #140505982248672: nam='SQL*Net message from client' ela= 11353687 driver id=1650815232 #bytes=1 p3=0 obj#=-1 tim=1671418598785274
CLOSE #140505981123976:c=0,e=6,dep=0,type=0,tim=1671418598785348
CLOSE #140505982248672:c=0,e=7,dep=0,type=0,tim=1671418598785371
*** 2022-12-19 10:56:38.785
Processing Oradebug command 'event 10046 trace name context off'
*** 2022-12-19 10:56:38.785
Oradebug command 'event 10046 trace name context off' console output: <none>
*** 2022-12-19 10:56:55.612
Processing Oradebug command 'tracefile_name'
*** 2022-12-19 10:56:55.612
Oradebug command 'tracefile_name' console output:
/u01/app/oracle/diag/rdbms/orcl/orcl/trace/orcl_ora_9522.trc
tkprof 命令翻译 trace 文件
[oracle@hisdb u01]$ tkprof /u01/app/oracle/diag/rdbms/orcl/orcl/trace/orcl_ora_9522.trc
output = /u01/123.txt
TKPROF: Release 11.2.0.4.0 - Development on 星期一 12月 19 11:05:54 2022
Copyright (c) 1982, 2011, Oracle and/or its affiliates. All rights reserved.
[oracle@hisdb u01]$ more 123.txt
TKPROF: Release 11.2.0.4.0 - Development on 星期一 12月 19 11:05:54 2022
Copyright (c) 1982, 2011, Oracle and/or its affiliates. All rights reserved.
Trace file: /u01/app/oracle/diag/rdbms/orcl/orcl/trace/orcl_ora_9522.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
********************************************************************************
SQL ID: 3nkd3g3ju5ph1 Plan Hash: 2853959010
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 0 0.00 0.00 0 0 0 0
Execute 1 0.00 0.60 0 0 0 0
Fetch 1 0.00 0.00 0 4 0 1
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total 2 0.00 0.60 0 4 0 1
Misses in library cache during parse: 0
Misses in library cache during execute: 1
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)
Number of plan statistics captured: 1
Rows (1st) Rows (avg) Rows (max) Row Source Operation
---------- ---------- ---------- ---------------------------------------------------
1 1 1 TABLE ACCESS BY INDEX ROWID OBJ$ (cr=4 pr=0 pw=0 time=47 us cost=4 size=84 card=1)
1 1 1 INDEX RANGE SCAN I_OBJ2 (cr=3 pr=0 pw=0 time=27 us cost=3 size=0 card=1)(object id 37)
********************************************************************************
SQL ID: 1gu8t96d0bdmu Plan Hash: 3526770254
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 0 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 4 0 1
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total 2 0.00 0.00 0 4 0 1
Misses in library cache during parse: 0
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)
********************************************************************************
SQL ID: 7ng34ruy5awxq Plan Hash: 3992920156
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 0 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 3 0 0
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total 2 0.00 0.00 0 3 0 0
Misses in library cache during parse: 0
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)
********************************************************************************
SQL ID: 83taa7kaw59c1 Plan Hash: 3765558045
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 0 0.00 0.00 0 0 0 0
Execute 1 0.00 0.00 0 0 0 0
Fetch 2 0.00 0.00 0 3 0 1
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total 3 0.00 0.00 0 3 0 1
Misses in library cache during parse: 0
Optimizer mode: CHOOSE
Parsing user id: SYS (recursive depth: 1)
********************************************************************************
SQL ID: 96g93hntrzjtr Plan Hash: 2239883476
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
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 1 0.00 0.00 0 3 0 1
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total 2 0.00 0.00 0 3 0 1
Misses in library cache during parse: 0
Optimizer mode: RULE
Parsing user id: SYS (recursive depth: 1)
********************************************************************************
SQL ID: 27uhu2q2xuu7r Plan Hash: 3617692013
select *
from
t1
call count cpu elapsed disk query current rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse 1 0.00 0.60 0 17 0 0
Execute 1 0.00 0.00 0 0 0 0
Fetch 2 0.00 0.00 0 2 0 1
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total 4 0.00 0.60 0 19 0 1
Misses in library cache during parse: 1
Optimizer mode: ALL_ROWS
Parsing user id: SYS
Number of plan statistics captured: 1
Rows (1st) Rows (avg) Rows (max) Row Source Operation
---------- ---------- ---------- ---------------------------------------------------
1 1 1 TABLE ACCESS FULL T1 (cr=2 pr=0 pw=0 time=15 us cost=2 size=3 card=1)
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.18 0.18
********************************************************************************
SQL ID: 9babjv8yq8ru3 Plan Hash: 0
BEGIN DBMS_OUTPUT.GET_LINES(:LINES, :NUMLINES); END;
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 1
Fetch 0 0.00 0.00 0 0 0 0
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total 2 0.00 0.00 0 0 0 1
Misses in library cache during parse: 0
Optimizer mode: ALL_ROWS
Parsing user id: SYS
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 11.35 11.35
********************************************************************************
OVERALL TOTALS FOR ALL NON-RECURSIVE STATEMENTS
call count cpu elapsed disk query current rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse 2 0.00 0.60 0 17 0 0
Execute 2 0.00 0.00 0 0 0 1
Fetch 2 0.00 0.00 0 2 0 1
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total 6 0.00 0.60 0 19 0 2
Misses in library cache during parse: 1
Elapsed times include waiting on following events:
Event waited on Times Max. Wait Total Waited
---------------------------------------- Waited ---------- ------------
SQL*Net message to client 4 0.00 0.00
SQL*Net message from client 4 11.35 17.67
OVERALL TOTALS FOR ALL RECURSIVE STATEMENTS
call count cpu elapsed disk query current rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse 0 0.00 0.00 0 0 0 0
Execute 5 0.00 0.60 0 0 0 0
Fetch 6 0.00 0.00 0 17 0 4
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total 11 0.00 0.60 0 17 0 4
Misses in library cache during parse: 0
Misses in library cache during execute: 1
2 user SQL statements in session.
5 internal SQL statements in session.
7 SQL statements in session.
********************************************************************************
Trace file: /u01/app/oracle/diag/rdbms/orcl/orcl/trace/orcl_ora_9522.trc
Trace file compatibility: 11.1.0.7
Sort options: default
1 session in tracefile.
2 user SQL statements in trace file.
5 internal SQL statements in trace file.
7 SQL statements in trace file.
7 unique SQL statements in trace file.
176 lines in trace file.
0 elapsed seconds in trace file.
只能看懂个大概,后面用到的时候再补充吧。