续上篇：http://blog.csdn.net/bisal/article/details/39225373

4. 10046事件

通过10046事件也可以查看目标SQL的执行计划。像10046这种事件，都不是Oracle官方文档中可以查询到的，这些事件一般用于调试目的，因此往往可以使用他们找到问题更详细的信息。

10046事件和之前的explain plan、DBMS_XPLAN包以及AUTOTRACE开关的区别在于，10046事件产生的trc文件中明确显示了目标SQL实际执行计划中每一步所消耗的逻辑读、物理读和花费的时间，执行计划的成本分析，进而可以看出为什么Oracle对于SQL选择了这样的执行计划，而不是那样的执行计划，之所以说是实际的执行计划，从10046事件执行的过程就可以看出来：

(a) 在当前session激活10046事件。

(b) 在此session中执行SQL。

(c) 关闭此session的10046事件。

真正执行的SQL，对应的执行计划可以在trc文件中找到。这个trc文件会记录SQL的执行计划和资源消耗，命名格式“实例名_ora_当前session的spid.trc”。

(1). 激活10046事件：

有两种方法：

(a) alter session set events ‘10046trace name context forever, level 12‘;

(b) oradebug setmypid/oradebug setospid SPID;

oradebug event 10046 trace name context forever, level 12;

(2).查看10046产生的trc文件名和路径的方法：

(a)show parameter USER_DUMP_DEST显示trc文件存储的路径 -> 查找对应当前session的trc文件(若当前是单用户，则是最新产生的文件)。

实验：

(b) 使用上述(b)的ordebug产生trc文件，可以用oradebug tracefile_name得到trc文件名和路径。

oradebug有很多需要说的，首先这是sqlplus特有的命令，在PLSQL Developer中执行会提示无效的SQL语句，例如：

其次它是sysdba角色的命令，使用非sysdba执行会提示ORA-01031权限不足，例如：

使用sysdba登录后，可以查看oradebug的帮助：

尽管oradebug用的时候需要使用sysdba登录，看似有些麻烦，但和第一种alter session的方法相比，最大的好处就是alter session只能针对当前会话或系统级，即alter session或alter system设置，如果设置非本会话的跟踪，此时就可以用oradebug了，(据说dbms_system、dbms_monitor和dbms_support也可以实现相同的需求，但没有试过)。

使用oradebug设置10046事件之前需要首先设置待跟踪的会话：

(a) 跟踪本会话，使用：oradebug setmypid即可。

(b) 跟踪非本会话，使用：oradebug setospid SPID(来自v$process)。

查找SPID的方法：

(a) select * from v$session a where audsid = userenv(‘sessionid‘);返回SID值。

(b) select s.USERNAME,
       s.OSUSER,
       s.SID,
       s.PADDR,
       s.PROCESS,
       p.spid     os_process_id,
       p.pid      oracle_process_id
  from v$session s, v$process p
  where s.paddr = p.addr
   and s.username = upper(‘待跟踪session用户名‘)
   and s.SID = (a)返回的SID;

例如：

其中：

v$process中的SPID是指操作系统的进程，即操作系统的PID。
v$session中的pid, serial#是oracle分配的PID。

此时如果需要跟踪24061这个session执行的SQL，可以用oradebug setospid 24061，然后oradebug event 10046 trace name context forever, level 12;就打开了10046事件。

接着可以通过oradebug tracefile_name查看trace文件名和路径，例如：

看下petest_ora_22756.trc的内容：

除了机器、实例、进程等基本信息外，真正写入的内容：

Received ORADEBUG command ‘tracefile_name‘ from process Unix process pid: 22235, image:

表示接收到来自Unix的操作系统进程PID是22235的ORADEBUG命令，参数tracefile_name。

显然22235这个进程是sqlplus登陆后执行ORADEBUG的客户端，例如：

(3).关闭10046的方法：

(a) alter session set events ‘10046 trace name context off‘;

分别对应两种打开10046事件的方法。

(4). 再说说oradebug和alter session打开10046事件产生trace文件的区别：

(a) 使用alter session打开10046事件时，如果未执行SQL，则不会产生trace文件。

(b) 使用oradebug event 10046 trace name context forever, level 12;打开10046事件，此时就已经产生trace文件，除基本信息外，主要是一行：

WAIT #0: nam=‘SQL*Net message to client‘ ela= 2 driver id=1650815232 #bytes=1 p3=0 obj#=-1 tim=1379395297285576

当使用oradebug event 10046 trace name context off;关闭10046事件，会写入一行：

WAIT #0: nam=‘SQL*Net message from client‘ ela= 30946429 driver id=1650815232 #bytes=1 p3=0 obj#=-1 tim=1379395328232564

(5). trace跟踪文件：

上面说了打开和关闭10046的两种常用方法，下面简单看看trace文件都包含了什么，为什么说10046这种事件是用于调试的，我现在不能精通所有内容，大概谈谈理解。

首先执行命令打开10046事件、执行SQL、关闭10046事件，例如：

查看产生的trace文件：

两个"============"之前的内容，是执行SQL产生的信息，之前和之后的内容，应该是打开和关闭10046事件的信息。

红线框内的是这条SQL用的执行计划，从文件中看，一共有5步，括号内的是相关消耗：

执行计划第一步：MERGE JOIN，逻辑读(cr)是11，物理读(pr)是0，时间(time)是378微秒。

执行计划第二步：TABLE ACCESS BY INDEX ROWID TEST1，逻辑读(cr)是4，物理读(pr)是0，时间(time)是139微秒。

执行计划第三步：INDEX FULL SCAN SYS_C0016790，逻辑读(cr)是2，物理读(pr)是0，时间(time)是86微秒。

执行计划第四步：SORT JOIN，逻辑读(cr)是7，物理读(pr)是0，时间(time)是266微秒。

执行计划第五步：TABLE ACCESS FULL TEST2，逻辑读(cr)是7，物理读(pr)是0，时间(time)是128微秒。

这里trc文件是一种裸trace文件，内容可看，但不是那么直观，可以使用tkprof命令翻译trc文件。例如：

查看生成的tkprof文件：

从这里可以更清楚地看到每步执行计划返回的行数，以及顺序关系，按照@dbsnake的执行计划读取口诀：“先从最开头一直连续往右看，直到看到最右边的并列的地方；对于不并列的，靠右的先执行；如果见到并列的，就从上往下看，对于并列的部分，靠上的先执行”。简单分析下：

(a)INDEXFULL ...

先使用SYS_C0016790主键索引进行索引快速全扫描，这里SYS_C0016790是TEST1的主键，即t1id列。

(b)TABLE ACCESS FULL ...

全表扫描TEST2表。

(c)TABLE ACCESS BY ...

根据TEST1主键索引返回的ROWID，查询对应数据项。产生结果集1。

(d)SORT JOIN

按照TEST2的t2id列排序。产生结果集2。

(e)MERGEJOIN

遍历结果集1，即取出结果集1的第1条记录，和结果集2中按照t1.t1id=t2.t2id的条件判断是否存在匹配记录，再取出结果集1的第2条记录继续判断，直到遍历完成结果集1。

这里用到的是“排序合并连接”，执行计划中对应的关键字是“MERGE JOIN”和“SORT JOIN”，正常来讲，两个表第二步都应该是SORT JOIN，但这里表TEST1却是TABLE ACCESS BY INDEX ROWID TEST1，我想原因应该是：

(a)对TEST2表的扫描使用的是INDEX FULL SCAN SYS_C0016790，即使用的索引快速全扫描，扫描t1id的主键索引数据块。

(b) 索引都是有序的，因此INDEX FULL SCAN SYS_C0016790的结果也是相当于排序的。

(c) 既然之前已经是排序的结果，那么按照有序索引对应的ROWID，找到对应的记录也是有序的，TABLE ACCESS BY INDEX ROWID TEST1，所以不用显示SORT JOIN再次排序了。

未完待续。。。

?To Be Continued ...

查看Oracle执行计划的几种常用方法-系列3