故障現象：

兩個Java寫的后台批處理同時執行時，發生了死鎖現象。

 

排查手法：

通過查詢視圖，找到被鎖住的對象v$locked_object，根據其locked_mode，判斷其鎖類型

查詢SQL語句：

select l.xidusn, l.object_id, o.owner, o.object_name, 
l.session_id, l.oracle_username, l.os_user_name, l.process, 
decode(l.locked_mode, 0, '',
        1, 'NULL',
        2, '(SS)',
        3, '(SX)',
        4, '(S)',
        5, '(SSX)',
        6, '(X)',
        '???') locked_mode
from v$locked_object l, dba_objects o
where l.object_id = o.object_id

判斷查詢結果，發現兩個Session對同一個表的數據行進行了排他。

 

用以下的語句對視圖v$sqltext進行查詢，可以得到當前正在執行的SQL語句，以及執行SQL語句的session

select username, osuser, machine, terminal, program, 
    sid, serial#, status, sql_address, sql_text
from v$session ss, v$sqltext sq
where type = 'USER'
and ss.sql_address = sq.address
order by ss.sid, ss.serial#, sq.piece

可以發現對同一表中的同一數據行進行更新的兩條SQL語句。

通過這兩條SQL語句，可以定位Java程序中導致問題的代碼。

 

問題原因分析：

對Java代碼進行分析后，發現有一個按照數據行主鍵，逐行循環處理的操作。

不幸的是，循環前，從KeySet（）生成的主鍵列表沒有進行排序，導致每次循環的執行順序都是隨機的。

例如，假設兩個session都想要對數據行A,B,C,D進行處理，很有可能session1先處理了A,B，

而此時session2剛好處理完了C,D。這樣，session1想要繼續處理的C,D由於正被session2給鎖住，所以無法繼續執行。

session2想要處理的A,B也被session1給鎖着，session2也無法繼續，兩個session最終都沒有辦法終止。

 

借助於ORACLE的TRACE文件

Oracle發現死鎖后，會在alert_[SID].log文件中輸出如下的警告信息：ORA-00060: Deadlock detected.

並提示去查看相應的*.trc文件。通過分析*.trc文件可以看到死鎖的詳細情況，

下面是一個*.trc文件的例子:

 

*** 2012-01-09 20:11:22.379
DEADLOCK DETECTED ( ORA-00060 )
 
[Transaction Deadlock]
 
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:
 
Deadlock graph:
                       ---------Blocker(s)--------  ---------Waiter(s)---------
Resource Name          process session holds waits  process session holds waits
TX-000a0006-0000f48f        65     101     X             64     102           X
TX-0007000f-0000d8a3        64     102     X             65     101           X
 
session 101: DID 0001-0041-00000002 session 102: DID 0001-0040-00000002
session 102: DID 0001-0040-00000002 session 101: DID 0001-0041-00000002 
 這里明確指出了發生死鎖的兩個session的ID
Rows waited on:
  Session 101: obj - rowid = 0008915A - AACJFaAAFAAEwq1AAA
  (dictionary objn - 561498, file - 5, block - 1247925, slot - 0)
  Session 102: obj - rowid = 0008915A - AACJFaAAFAAEwq1AAI
  (dictionary objn - 561498, file - 5, block - 1247925, slot - 8)
 
----- Information for the OTHER waiting sessions -----
Session 102:
  sid: 102 ser: 2 audsid: 25260645 user: 87/USR_BAT flags: 0x41
  pid: 64 O/S info: user: ora_1, term: UNKNOWN, ospid: 5915
    image: oracle@pcXX
  client details:
    O/S info: user: root, term: unknown, ospid: 1234
    machine: pcXX program: JDBC Thin Client
    application name: JDBC Thin Client, hash value=2546894660
  current SQL:
 
UPDATE XXXX SET XXXX  這里是導致死鎖的SQL語句1

----- End of information for the OTHER waiting sessions -----
 
Information for THIS session:
 

*** 2012-01-09 20:11:22.530
----- Current SQL Statement for this session (sql_id=b0qn65w78t10b) -----
UPDATE XXXX SET XXXX 這里是導致死鎖的SQL語句2 

===================================================

 

※ log文件和trc文件的存放路徑，取決於Oracle的安裝路徑，可以借助於文件檢索功能。