了解ORA-00060和trace跟踪文件
了解ORA-00060和trace跟踪文件
朋友前两天问到ORA-00060错误的解决，首先，这种错误都是因为应用设计导致的，当不同的会话处理同一张表的不同行，或者不同表，或者不同事务的时候(这是比较复杂的)，如果出现处理次序的交叉，Oracle就会检测到，进而对其中一个会话抛出ORA-00060，强制回滚，释放锁资源，并将相关信息，写入跟踪文件，Oracle的这种设计，既进行了自恢复，而且记录了相关的信息，便于问题跟踪，值得我们借鉴。
默认设置中，ORA-00060错误对应的跟踪文件包含缓存游标、死锁 图、处理状态、相关会话的当前SQL语句，以及会话等待历史(Oracle 10g及以上的版本)，除了当前的SQL语句和死锁图，其他所有信息都属于接收到ORA-00060错误的会话。Oracle提供了个10027 event，10027事件能让DBA控制ORA-00060错误对应的诊断信息的数量和类型，他可以实现：
- 减小和ORA-00060错误对应的跟踪信息的占用空间，例如，当该问题无法解决的时候，执行该操作，降低业务影响。
- 在跟踪信息中加入系统状态转储数据或者调用栈，用来寻找死锁的根源。
10027有三个级别，1，2和4，其中第1级只包含一个死锁图和相关会话的当前SQL语句。第2级包含了系统状态转储信息(包含缓冲SQL和所有会话的等待历史)，不仅仅是死锁相关会话的当前SQL语句。第4级包含的调用栈信息用途不大，通过这些信息，能知道检测到死锁的时候，Oracle服务器进程正在执行哪个C函数。
一般使用2级，就可以满足要求。另外，锁会在ORA-00060跟踪文件写好才被释放，所以第1级的10027能确保会话更快地响应。
接下来我们用测试数据，验证下ORA-00060，以及跟踪文件。
创建测试表，增加测试数据，
SQL> create table a(id number, a varchar2(1));Table created.SQL> insert into a values(1, 'a');1 row created.SQL> insert into a values(2, 'b');1 row created.SQL> commit;Commit complete.SQL> select * from a; ID A---------- - 1 a 2 b
Table created.
SQL> insert into a values(1, 'a');
1 row created.
SQL> insert into a values(2, 'b');
1 row created.
SQL> commit;
Commit complete.
SQL> select * from a;
ID A
---------- -
1 a
2 b按照如下顺序，进行的操作，就会出现ORA-00060，
t1时间点，会话1：更新id=1的行
t2时间点，会话2：更新id=2的行
此时两个会话，均能正常执行。
t3时间点，会话1：更新id=2的行，该行锁正被会话2占用，因此会话1处于hung，等待中。
t4时间点，会话2：更新id=1的行，该行锁正被会话1占用，此时出现了会话1和会话2锁资源的交叉等待，1等待2，2等待1，Oracle检测到后，就会让会话1抛出ORA-00060的错误，此时会话1更新id=1的行锁未释放，所以会话2更新id=1的操作，当前状态是hung，等待会话1释放锁资源，
我们设置level=2级的10027 event，
alter system set events '10027 trace name context forever, level 2';system set events '10027 trace name context forever, level 2';默认设置，和10027设置得到的trace，除了状态转储信息外，其他内容，基本一致，
trace开始就提示了，“The following deadlock is not an ORACLE error. It is a deadlock due to user error in the design of an application or from issuing incorrect ad-hoc SQL. The following information may aid in determining the deadlock:”，这个错误不是Oracle的，而是因为应用设计导致的。
从死锁图，能看出会话15和会话67之间存在相互依赖的锁循环链，持有和等待X锁。
“Rows waited on”根据rowid信息，能知道两个会话，当前互相等待的行是什么。
“Information for the OTHER waiting sessions”表示未抛出ORA-00060会话正在执行的操作，包括用户名、SID、PID、终端、应用名称、SQL等，
“Information for THIS session”表示抛出ORA-00060的会话正在执行的操作，
接下来“PROCESS STATE”进程状态信息了，能看明白的，就很少了，
通过trace，可以知道发生死锁的两个会话，当前各自执行的操作是什么，因为是应用的设计问题，所以就可以据此，在应用端过代码，找到可能出现操作次序交叉的逻辑，这是问题的关键，因此跟踪文件，对定位死锁问题，还是很重要的。
实测，使用level=2级的10027事件，打印出来的trace大小1.8M，使用默认设置，打印出来的trace大小352K，主要多了系统状态转储信息。
默认设置和10027得到的跟踪文件内容比较，
内容/级别 | 默认值 | 第1级 | 第2级 | 第4级 |
|---|---|---|---|---|
缓存游标 | yes | no | yes | yes |
调用栈跟踪 | no | no | no | yes |
死锁图 | yes | yes | yes | yes |
处理状态 | yes | no | yes | yes |
SQL语句 | yes | yes | yes，对所有会话 | yes |
会话等待历史 | yes | no | yes，对所有会话 | yes |
系统状态 | no | no | yes | no |
虽然Oracle抛出ORA-00060，不代表应用不用做什么了，图中，t4时刻，会话1抛出ORA-00060，此时，只强制回滚t3时刻会话1的这条SQL，换句话说，会话2在t4时刻的这条SQL，会处于hung，因为id=1的行锁是会话1在t1时刻得到的，此时并未释放，因此在应用程序的设计中，针对抛出的ORA错误，应该try-catch到，并且显式ROLLBACK，才会让其他会话继续执行，否则这种操作，还是有问题的，
参考：
1. 关于10027事件，参考《未公开的Oracle数据库秘密》这本书。
2. 《困扰许久的一个ORA-00060错误解决》介绍了个事务锁导致的ORA-00060，这个复杂场景，当时配合开发，鼓捣了很久，才梳理清楚。导致ORA-00060的锁，不仅仅是常见的行或者表，他可以是anything-“任何”，“The resources in question could be anything. In the server, they could be rows, tables, ITL slots, or library cache or row cache locks.”，这话还要仔细品味才行。