ITPub博客

深入理解Oracle调试事件:10046事件详解

Oracle 作者:波仔strong 时间:2018-02-22 11:54:34 0 删除 编辑

       前也写过一篇10046的文章:10046简介

       今天,Think想和大家一起共同深入去理解一下Oracle的这些调试事件
     
       10046事件是SQL_TRACE的扩展,被戏称为"吃了兴奋剂的SQL_TRACE"
       有效的追踪级别:
       
       ① 0级:SQL_TRACE=FASLE
       ② 1级:SQL_TRACE=TRUE,这是缺省级别
       ③ 4级:1级+绑定变量
       ④ 8级:4级+等待事件
       ⑤ 12级:4级+8级
       
       对于4级的10046,若用tkprof格式化,则会隐藏每一点SQL语句在做什么以及怎么做
       对于8级的10046,等待事件散得到处多是,此时我们不妨用tkprof对等待事件进行汇总
       所以,理解扩展SQL跟踪文件的格式,是每一个面临性能问题或故障排除任务的DBA的必备技能
          
       ㈠ 为什么需要10046?
     
          对一个拥有alter session权限但是没有被授权DBA角色的数据库用户
          alter session set events是在他自己会话中启动扩展SQL跟踪的唯一方法
          通过这种方法将等待事件或者绑定变量包含在SQL跟踪文件中,然后进行优化或者错误诊断    
          10046 看到语句的执行过程,知道语句的执行计划,包括各种步骤,关联方式,分别在哪些步骤耗时多少,有哪些等待事件等
          这些都是优化的基础,知道这些,就知道该如何优化以及troubleshoting
          
       
     
       ㈡ 如何获取trc文件?


          这里主要介绍3种方法
          
          ① 使用tracefile_identifier,比如:
             alter session set tracefile_identifier=\'Think\'


          ② oradebug,详细请请参看Think之前的一篇博客:oradebug


          ③ 使用下面的脚本:

hr@ORCL> ed
Wrote file afiedt.buf

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

TRACE_FILE_NAME
-----------------------------------------------------------------
/u01/app/oracle/admin/orcl/udump/orcl_ora_4012.trc

       ㈢ 如何读懂10046事件的文件?
       
       ① 数据库调用

       
       含3个子分类:解析,执行和获取
       这3个分类与通过调用DBMS_SQL的子例程DBMS_SQL.PARSE,DBMS_SQL.EXECUTE,DBMS_SQL.FETCH_ROWS来跑SQL的步调相一致
       
       解析
       解析在跟踪文件中通常通过两个相邻的条目表示
       第一个是PARSING IN CURSOR,第二个是PARSE

PARSING IN CURSOR #9 len=28 dep=0 uid=55 oct=2 lid=55 tim=1327904235010505 hv=119728103 ad=\'2fc6ae84\'
insert into t values(\'ooxx\')
END OF STMT
PARSE #9:c=52003,e=65698,p=0,cr=30,cu=0,mis=1,r=0,dep=0,og=1,tim=1327904235010494

     


     


       执行和获取同解析在格式上是一样的,这里就不赘述了


       ② commit和rollback和XCTEND条目格式

XCTEND rlbk=0, rd_only=0

       Oracle不需要客户端显示地开始一个事务,DBMS在第一个数据项目被修改或分布式操作执行后会自动打开一个事务
       比如,通过dblink从一个表执行select
       在trc中事务的边界通过XCTEND条目标记,格式如下:
       XCTEND rlbk=[0-1],rd_only=[0-1]

     


      


       ③ 执行计划,统计信息与STAT条目格式
       
       STAT条目报告了执行计划和统计信息

STAT #6 id=1 cnt=0 pid=0 pos=1 obj=18 op=\'TABLE ACCESS BY INDEX ROWID OBJ$ (cr=2 pr=0 pw=0 time=194 us)\'
STAT #6 id=2 cnt=0 pid=1 pos=1 obj=37 op=\'INDEX RANGE SCAN I_OBJ2 (cr=2 pr=0 pw=0 time=95 us)\'
  

       一组STAT条目的每一行代表了形成语句结果的行源
       所谓的行源,指从索引或表中检索的数据或者多表连接的中间结果(因为必须先进行两表连接)
       
       10g以后,STAT条目仅在TIMED_STATISTICS=TRUE,并且SQL_TRACE=TRUE时才被写入
       请注意,若STATISTICS_LEVEL=BASIC(缺省为TYPICAL)时会隐式设置TIMED_STATISTICS=FASLE

     


     


       ④ 等待事件和WAIT条目格式

WAIT #9: nam=\'SQL*Net message to client\' ela= 4 driver id=1650815232 #bytes=1 p3=0 obj#=52523 tim=1327922883350249
WAIT #9: nam=\'SQL*Net message from client\' ela= 301 driver id=1650815232 #bytes=1 p3=0 obj#=52523 tim=1327922883350743

WAIT #11: nam=\'db file sequential read\' ela= 253 file#=1 block#=420 blocks=1 obj#=355 tim=1327923455671258
WAIT #11: nam=\'db file sequential read\' ela= 7073 file#=1 block#=43998 blocks=1 obj#=355 tim=1327923455678537
WAIT #11: nam=\'db file sequential read\' ela= 91 file#=1 block#=43999 blocks=1 obj#=355 tim=1327923455678836
WAIT #11: nam=\'db file sequential read\' ela= 14433 file#=1 block#=53521 blocks=1 obj#=355 tim=1327923455693393

     


       ⑤ 绑定变量和BINDS条目格式
       
       绑定变量的详细信息包括绑定变量的数据类型和值
       通过这些信息我们可以得到最大化的诊断
       例如,索引列的数据类型与绑定变量的数据类型不匹配,导致索引失效,CPU使用率增加,因为还存在隐式数据类型转换
       一个BINDS条目的结构由后面跟着游标编号的单词BINDS和每一个绑定变量单独的子部分组成

BINDS #9:
kkscoacd
 Bind#0
  oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
  oacflg=08 fl2=0001 frm=00 csi=00 siz=24 off=0
  kxsbbbfp=b7ee5a5c  bln=22  avl=02  flg=05
  value=20
 Bind#1
  oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00
  oacflg=08 fl2=0001 frm=00 csi=00 siz=24 off=0
  kxsbbbfp=b7ee5a38  bln=24  avl=02  flg=05
  value=3

       当将绑定变量与子部分相关联时,不用关心数字,他们会被包含在绑定变量的名称中,例如 ":B1"

     


     



     



hr@ORCL> select dump(employee_id) from employees where rownum=1;

DUMP(EMPLOYEE_ID)
----------------------------------------------------------------------------------------------------
Typ=2 Len=2: 194,2

       这里的Typ就是数据类型编号
       
       下次Think再把优化或者诊断的案例给附上,此文未完待续哦,,


<!-- Baidu Button BEGIN -->

来自 “ ITPUB博客 ” ,链接:http://blog.itpub.net/31485142/viewspace-2151140/,如需转载,请注明出处,否则将追究法律责任。

上一篇: oracle的ITL
下一篇: ORACLE事件跟踪
请登录后发表评论 登录
全部评论

注册时间:2017-08-25

博文推荐

Oracle 12c中的with子句增强

lhrbest

Oracle实现自增列(序列+触发器实现、Identity Columns新特性、序列作为默认值)

lhrbest

oracle数据库自动发邮件实现报警功能

甲骨文技术支持

[20181114]一条sql语句的优化.txt

lfree

[20181112]Private Temporary Tables Oracle Database 18C.txt

lfree

long2varchar 把long转换为varchar2

raysuen

UTL_FILE.PUT写入txt时超过32k报ORA-29285: 文件写入错误

darren__chan

[20181108]12c user_dump_dest与background_dump_dest参数.txt

lfree

数据库对象比如表放入内存,行发生改变不会自动同步到内存的总结

lusklusklusk

expdp慢的一次处理思路,最后发现原来遇到了bug

lusklusklusk