oracle怎样跟踪一条sql语句的执行过程？

Posted 2023-04-17

就跟程序调试似的，可以把每个变量及中间结果都显示出来。
感谢两位的回答，不过我想要的是跟踪SQL的执行循序，和中间结果。
比如：select a.id,b.name
from tablename1 a
inner join tablename2 b
on a.id = b.id
where a.XX = b.YY
SQL可能写的不对，我想知道这个SQL语句是怎么执行的，比如，第一步执行from，确定数据源；第二步执行inner join ，第三步执行on确定连接条件，并且组织出一个视图（我这么理解的），这个视图的记录包含哪些。可能我说的步骤不对，不过我就是想知道这个一个过程！
小弟先谢谢各位大虾了，帮帮忙分析一下……

需要建立一张表来记录
explain plan SET statement_id='name' FOR (这里是你要调试的语句 )

SELECT
A.OPERATION,
OPTIONS,
OBJECT_NAME,
OBJECT_TYPE,
ID,
PARENT_ID
FROM
PLAN_TABLE A
WHERE
STATEMENT_ID='name'
ORDER BY
Id;

ID 'name'是一个标识，你可以自己取，字段有很多个，以下是各个字段的解释（可能格式不对，你可以复制后看）：

字段名 字段类型 含义
STATEMENT_ID VARCHAR2(30) explain PLAN 语句中所指定的最优STATEMENT_ID 参数值, 如果在EXPLAN PLAN语句中没有使用SET STATEMENT_ID，那么此值会被设为NULL。

REMARKS VARCHAR2(80) 与被解释规划的各步骤相关联的注释最长可达80 字节

OPERATION VARCHAR2(30) 各步骤所执行内部操作的名称在某条语句所产生的第一行中该列的可能取值如下DELETE STATEMENT INSERT STATEMENT SELECT STATEMENT UPDATE STATEMENT

OPTIONS VARCHAR2(30) 对OPERATION 列中所描述操作的变种

OBJECT_NODE VARCHAR2(128) 用于访问对象的数据库链接database link 的名称对于使用并行执行的本地查询该列能够描述操作中输出的次序

OBJECT_OWNER VARCHAR2(30) 对于包含有表或索引的架构schema 给出其所有者的名称

OBJECT_NAME VARCHAR2(30) 表或索引的名称

OBJECT_INSTANCE INTEGER 根据对象出现在原始original 语句中的次序所给出的相应次序编号就原始的语句文本而论其处理顺序为自左至右自外向内景象扩张view

OBJECT_TYPE VARCHAR2(30) 用于提供对象描述性信息的修饰符例如索引的NON-UNIQUE

OPTIMIZER VARCHAR2(255) 当前优化程序的模式

ID INTEGER 分配给执行规划各步骤的编号

PARENT_ID INTEGER 对ID 步骤的输出进行操作的下一个执行步骤的ID

POSITION INTEGER 对于具有相同PARENT_ID 的步骤其相应的处理次序

COST INTEGER 根据优化程序的基于开销的方法所估计出的操作开销值对于使用基于规则方法的语句该列为空该列值没有特定的测量单位它只是一个用于比较执行规划开销大小的权重值

CARDINALITY INTEGER 根据基于开销的方法对操作所访问行数的估计值

BYTES INTEGER 根据基于开销的方法对操作所访问字节的估计
=============================================

你按照我说的做，后面用
SELECT
*
FROM
PLAN_TABLE A
WHERE
STATEMENT_ID='name'

结果已经很清楚了，全部满足你的要求。
各列的具体含义上面已经给出。 参考技术A 跟踪语句的执行最详细的是设置10046事件:
alter session set event "10046 trace name context forever ,level 12";

你的语句;

alter session set event "10046 trace name context off ";

然后到udump目录中打开日期最新的文件,里面有详细你的语句执行情况
包括执行的哪些递归调用,逻辑读,物理读等待事件,绑定变量的值...... 参考技术B 你要知道的步骤按照owens1818的explain plan 的做法就能够知道了。
查看一个语句的执行步骤，也可以通过一些工具来看，比如toad，sqldeveloper，sqlplus （set autotrace on ） 等。那样看起来比直接查询plan_table直观一些，
不过确实不能知道每一步骤中具体的记录。具体的记录只要你知道了每一步做什么，你自己就能够想出来了。

在Oracle中利用SQL_TRACE跟踪SQL的执行

当你在执行一条SQL语句非常慢的时候,你是不是想问Oracle怎么执行这条语句的呢?
Oracle提供的SQL_TRACE工具可以让你知道你执行的SQL究竟做了什么.执行的过程会被
输出到trace文件中.
下面用例子来跟踪一个SQL语句的执行情况:
SQL> create table t as select rownum as id,object_name from dba_objects;
Table created.
SQL> create index t_ind on t(id);
Index created.
SQL> alter session set tracefile_identifier=‘mysession‘;
Session altered.
生成的trace文件的路径是$ORACLE_BASE/admin/SID/udump目录.
上面的语句是让生产trace文件名包括mysession,如本例中在
D:\oracle\product\10.2.0\admin\orcl\udump生成了orcl_ora_5732_mysession.trc
SQL> alter session set sql_trace=true;
Session altered.
SQL> select * from t where id=123;
ID     OBJECT_NAME
123    I_ACCESS1
SQL> alter session set sql_trace=false;
Session altered.
一般来讲生成的trace文件比较难读,可以用tkprof来生成一个更可读的文件.
注意tkprof是Oracle带的一个命令行工具,不是SQLPLUS命令.
在另外一个命令行中进入D:\oracle\product\10.2.0\admin\orcl\udump目录
D:\oracle\product\10.2.0\admin\orcl\udump>tkprof orcl_ora_5732_mysession.trc orcl_ora_5732_mysession.txt
TKPROF: Release 10.2.0.1.0 - Production on Fri Sep 14 16:59:12 2012
Copyright (c) 1982, 2005, Oracle.  All rights reserved.
打开 orcl_ora_5732_mysession.txt文件,可以看到执行SQL的信息:
select *
from
 t where id=123
call     count       cpu    elapsed       disk      query    current        rows
------- ------  -------- ---------- ---------- ---------- ----------  ----------
Parse        1      0.04       0.30          0          2          0           0
Execute      1      0.00       0.00          0          0          0           0
Fetch        2      0.00       0.00          0          4          0           1
------- ------  -------- ---------- ---------- ---------- ----------  ----------
total        4      0.04       0.30          0          6          0           1
Misses in library cache during parse: 1
Optimizer mode: ALL_ROWS
Parsing user id: 61  
Rows     Row Source Operation
-------  ---------------------------------------------------
      1  TABLE ACCESS BY INDEX ROWID T (cr=4 pr=0 pw=0 time=86 us)
      1   INDEX RANGE SCAN T_IND (cr=3 pr=0 pw=0 time=67 us)(object id 57205)
********************************************************************************
对于每一条SQL语句,都包含3个步骤:
Parse:SQL的分析阶段.
Execute:SQL的执行阶段.
Fetch:数据提取阶段.(对于一次SQL操作,Fetch可能多次)
横向的标题头为:
count:当前的操作执行的次数.
cpu:当前操作消耗的CPU时间(秒).
elapsed:当前操作消耗的时间(CPU时间加等待时间).
disk:磁盘的IO次数.
query:当前操作的一致性读取的数据块数.
current:前操作的current读取的数据块数(通常在update操作时发生).
rows:处理的记录行数.

Misses in library cache during parse是指是不是重用了执行计划,如果同一条SQL语句第二次执行,
其值为零,这里是1,表示在共享池里没有这一条SQL的执行计划,发生了一次硬解析.
Optimizer:优化器模式.
Parsing user id:分析的用户ID.
Row Source Operation部分包含的实际消耗的资源.
cr:一致性读取的数据块,相当于Fetch的query.
pr:物理读, 相当于Fetch的disk.
pw:物理写.
time:当前操作的执行时间.

同时我们在trace文件里还会发现对系统表的访问的SQL,这就是通常说的递归SQL.

还可以利用10046事件来跟踪SQL,它比SQL_TRACE提供更详细的信息.它有LEVEL 1,4,8,12四个级别.
其中Level 1 相当于 SQL_TRACE.下面是10046使用的例子
SQL> alter session set events ‘10046 trace name context forever,level 4‘;
Session altered.
.... some SQL statements
SQL> alter session set events ‘10046 trace name context off‘;
Session altered.