欢迎光临
我们一直在努力

10046 tracefile注释

10046可看到语句的执行过程,包括各种步骤、关联方式、分别在哪些步骤耗时多少、有哪些等待事件等。

1.数据库调用

 分三个步骤:解析、执行和获取

 

解析:


 


PARSING IN CURSOR #140186891763128 len=84 dep=0 uid=0 oct=3 lid=0 tim=1542789588408762 hv=2629212467 ad='9b65c8d8' sqlid='d2knts2fbd69m'




  select count(1)



  from dba_objects a,scott.tb_test2 b



  where  a.object_id = b.object_id



  END OF STMT



  PARSE #140186891763128:c=14309,e=14309,p=0,cr=12,cu=0,mis=1,r=0,dep=0,og=1,plh=2135238665,tim=1542789588408759

  

 

PARSING IN CURSOR参数:

   

len:SQL语句长度


   dep:递归调用深度


   uid:解析用户标识符,对应dba_users.user_id、v$sql.parsing_user_id


   oct:Oracle命令类型. 对应v$sql.command_type、v$session.command. create table:1 delete:7 select:3 insert:2 update:6 truncate:85 drop table:12


   lid:解析模式标识符,对应dba_users.user_id、v$sql.parsing_schema_id


   tim:Timestamp.当前行写入文件时v$timer中的值


   hv:sql hash value,对应v$sql.hash_value


   ad:sql address, 对应v$sql.address


   sqlid:sql id,对应v$sql.sql_id

  

 

PARSE参数:

   

c:CPU开销。9i之前厘秒,9i之后是微秒


   e:运行时间。9i之前厘秒,9i之后是微秒


   p:物理读次数


   cr:逻辑读的数据块


   cu:current模式读取的数据块


   mis:1:硬解析 0:软解析


   r:处理的行数


   dep:递归调用深度


   og:优化器模式: 1=All_Rows, 2=First_Rows, 3=Rule, 4=Choose


   tim:时间戳


   plh:plan hash value,执行计划的hash值,对应v$sql_plan.plan_hash_value、v$sql_plan_statistics_all.plan_hash_value、v$sqlstats.plan_hash_value

  

 

解析出错时:

 



PARSE ERROR #140092551821528:len=30 dep=0 uid=0 oct=3 lid=0 tim=1542780378216064 err=942





  select count(1) from scott.tb


  

 

PARSE ERROR参数:

   

len:


SQL语句长度


   dep:


递归调用深度


   uid:


解析用户标识符,对应dba_users.user_id、v$sql.parsing_user_id


   oct:


Oracle命令类型. 对应v$sql.command_type、v$session.command


   lid:


解析模式标识符,对应dba_users.user_id、v$sql.parsing_schema_id


   tim:Timestamp.


   err:Oracle error code (e.g. ORA-XXXXX) reported

  


 执行和获取:

 


EXEC #[CURSOR]:c=0,e=0,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=2295886755,tim=0



  FETCH #[CURSOR]:c=0,e=0,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=2295886755,tim=0

  参数与PARSE参数相同

2.事务条目


 


XCTEND rlbk=0, rd_only=0, tim=1542780757243972


 

 

XCTEND参数:

 

rlbk:1:rollback, 0:commit.


  rd_only :1:只读事务, 0:事务中有数据被修改.


  tim:时间戳

3.STAT条目


 


STAT #[CURSOR] id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=6 pr=0 pw=0 time=483 us)'




 STAT #[CURSOR] id=2 cnt=14 pid=1 pos=1 obj=75510 op='TABLE ACCESS FULL EMP (cr=6 pr=0 pw=0 time=432 us cost=2 size=0 card=14)'

 

 

STAT参数:

 

id:执行计划中指示行数据源顺序的标识符。通常一个执行计划的第一条STAT的id=1


  cnt:当前行源返回的行数


  pid:当前行源的父结点id


  pos:Position in explain plan.


  obj:行源的对象id,对应于dba_objects.object_id、v$sql_plan.object#


  op:执行的操作,如表访问、索引扫描、排序、联合等,对应v$sql_plan.operation


 

 

STAT执行统计参数:

 

cr:Consistent reads.逻辑读


  pr:Physical reads.物理读


  pw:Physical writes.物理写


  time:消耗的时间,微秒


  cost:CBO估计的成本


  size:估计的数据量,单位为字节。基于对象的统计信息


  card:估计的基数,基于对象的统计信息

4.WAIT条目


 


WAIT #140092549877616: nam='Disk file operations I/O' ela= 40 FileOperation=2 fileno=1 filetype=2 obj#=106 tim=1542780935083885




 WAIT #140092549877616: nam='db file sequential read' ela= 8945 file#=1 block#=69315 blocks=1 obj#=106 tim=1542780935092868



 WAIT #140092549877616: nam='db file sequential read' ela= 8859 file#=1 block#=69378 blocks=1 obj#=104 tim=1542780935101864

 

 

WAIT参数:

 

nam:等待事件名称


  ela:运行时间,微秒


  p1:等待事件的第一个参数


  p2:等待事件的第二个参数


  p3:等待事件的第三个参数


  obj#:对象编号,也就是dba_object.object_id


  tim:时间戳

5.绑定变量条目


 


BINDS #140092551821528:




  Bind#0



   oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00



   oacflg=03 fl2=1000000 frm=00 csi=00 siz=24 off=0



   kxsbbbfp=7f69d6c94c20  bln=22  avl=02  flg=05



   value=20

   

 

BINDS参数:

 

Bind#N:绑定变量被使用的位置


  oacdty:


绑定变量数据类型


  mxl:绑定变量值的最大长度


  mxlc


:未知


  mal:数组长度


  scl:比例.


  pre:精度.


  oacflg:指示绑定选项的特殊标志


  fl2:oacflg的第二部分


  frm:未知


  csi:数据库字符集或国际字符集的字符集标识符


  siz:给该chunk分配的内存总量


  off:该绑定缓冲区在chunk中的偏移


  kxsbbbfp:


绑定地址


  bln:绑定缓冲区长度


  avl:实际值的长度


  flg:绑定变量状态


  value:绑定变量值

  

 

数据类型代码:


  1         varchar2/nvarchar2


  2         number


  12       date


  96       char/nchar


  112     clob/nclob


  113     blob


  180     timestamp

 


 常见字符集和标识符:

 

1        US7ASCII


  31       WE8ISO8859P1


  46       WE8ISO8859P15


  170      EE8MSWIN1250


  178      WE8MSWIN1252


  871      UTF8


  873      AL32UTF8


  2000     AL16UTF16

6.释放游标


 


CLOSE #140092551821528:c=15,e=15,dep=0,type=0,tim=1542780378208132




参数:


  c:CPU开销。9i之前厘秒,9i之后是微秒


  e:运行时间。9i之前厘秒,9i之后是微秒


  dep:游标递归深度


  type:type of close operation


  tim:Timestamp 时间戳

赞(0)
【声明】:本博客不参与任何交易,也非中介,仅记录个人感兴趣的主机测评结果和优惠活动,内容均不作直接、间接、法定、约定的保证。访问本博客请务必遵守有关互联网的相关法律、规定与规则。一旦您访问本博客,即表示您已经知晓并接受了此声明通告。