17

ハングしているJavaプロセスで発生している問題を理解しようとしています。このプロセスは約4か月間本番環境で実行されており、今週初めにハングし始めました。プロセスのスレッドダンプを見ると、関連するすべてのスレッド(3)に次のようなスタックがあります。

    "TxnParser_1" prio=6 tid=0x69bd3400 nid=0x2534 runnable [0x6aa2f000]
   java.lang.Thread.State: RUNNABLE
        at java.net.SocketInputStream.socketRead0(Native Method)
        at java.net.SocketInputStream.read(SocketInputStream.java:129)
        at oracle.net.ns.Packet.receive(Unknown Source)
        at oracle.net.ns.DataPacket.receive(Unknown Source)
        at oracle.net.ns.NetInputStream.getNextPacket(Unknown Source)
        at oracle.net.ns.NetInputStream.read(Unknown Source)
        at oracle.net.ns.NetInputStream.read(Unknown Source)
        at oracle.net.ns.NetInputStream.read(Unknown Source)
        at oracle.jdbc.driver.T4CMAREngine.unmarshalUB1(T4CMAREngine.java:1099)
        at oracle.jdbc.driver.T4CMAREngine.unmarshalSB1(T4CMAREngine.java:1070)
        at oracle.jdbc.driver.T4C8Oall.receive(T4C8Oall.java:478)
        at oracle.jdbc.driver.T4CStatement.doOall8(T4CStatement.java:207)
        at oracle.jdbc.driver.T4CStatement.executeForDescribe(T4CStatement.java:790)
        at oracle.jdbc.driver.OracleStatement.executeMaybeDescribe(OracleStatement.java:1039)
        at oracle.jdbc.driver.T4CStatement.executeMaybeDescribe(T4CStatement.java:830)
        at oracle.jdbc.driver.OracleStatement.doExecuteWithTimeout(OracleStatement.java:1132)
        at oracle.jdbc.driver.OracleStatement.executeInternal(OracleStatement.java:1687)
        at oracle.jdbc.driver.OracleStatement.execute(OracleStatement.java:1653)
        - locked <0x40e22f88> (a oracle.jdbc.driver.T4CStatement)
        - locked <0x28f8d398> (a oracle.jdbc.driver.T4CConnection)
        at com.gcg.data.LogParsingInfo.initFromDB(LogParsingInfo.java:262)
        at com.gcg.om.OmQueueEntry.initParseInfoFromDB(OmQueueEntry.java:104)
        at com.gcg.om.GenericQueueEntry.run(GenericQueueEntry.java:237)
        at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
        at java.lang.Thread.run(Thread.java:619)

ロックを待機しているスレッドがないため、プロセスがデッドロックされません。作業を行っているこれらの3つのスレッドは、Oracleからの応答を待ってブロックされているだけです。少なくとも、私にはそのように見えます。

Oracleを見ると、v $ sessionをクエリすると、SQLは表示されませんが、これらのスレッドに関連付けられている接続の1つが現在クエリを実行しているように見えます。

select ... from v$session where ...;
SQL_ADDRESS      SQL_HASH_VALUE SQL_ID        SQL_CHILD_NUMBER SQL_EXEC_START SQL_EXEC_ID PREV_SQL_ADDR    PREV_HASH_VALUE PREV_SQL_ID   PREV_CHILD_NUMBER PREV_EXEC_START PREV_EXEC_ID
---------------- -------------- ------------- ---------------- -------------- ----------- ---------------- --------------- ------------- ----------------- --------------- ------------
              00              0                                                           0000000239F59EE8      1483377872 fqr8pndc6p36h                 5 26-JUL-12           32080545
              00              0                                                           0000000239F59EE8      1483377872 fqr8pndc6p36h                 5 26-JUL-12           32080546
0000000148CABD88     1784444892 a16hxxtp5sxyw                                             0000000239F59EE8      1483377872 fqr8pndc6p36h                 5 26-JUL-12           32080544

select * from v$sql where sql_id = 'a16hxxtp5sxyw';

no rows selected

私の質問は次のとおりです。

  1. 私の分析では、プロセスがOracleからの応答を待っているだけでブロックされているというのは正しいですか?
  2. このプロセスがブロックされている理由を理解するには、Oracleで何を探す必要がありますか?

更新しました:

DBA_WAITERSとDBA_LOCKSの検索に関するコメントに基づく

select * from dba_waiters;

no rows selected

select * from dba_locks where BLOCKING_OTHERS <> 'Not Blocking';

no rows selected 

dba_locksには98行ありましたが、すべてが「ブロックされていません」であるため、ロックの問題ではないと思いますか?問題のプロセスは3時間以上この状態になっているため、デッドロックは今までに検出されていたはずです。

私は、Oracleインスタンスは「正常」ではないという理論に基づいていますが、何を見るべきかについて途方に暮れています。Oracleサーバーを再起動するように要求されましたが、まだ実行されていません。

フォローアップの質問:v $sessionにv$sqlに存在しないsql_idが含まれているのは正常ですか?存在する場合、どのような条件下ですか?

4

2 に答える 2

9

問題は解決され、答えはv$sessionテーブルで正しかった。どうやら、Oracleセッションは単にロックする以外の理由でブロックする可能性があります。列FINAL_BLOCKING_SESSIONに注意してください。これは、ブロッキングの根本的な原因であるセッションを識別します。セッション845を調査したところ、クライアントプロセス(MACHINEとPORTで識別)が存在しなくなっていることがわかりました。DBAはセッション845を強制終了し、すべてが正常に戻りました。

SID     SERIAL# STATUS    PROGRAM          TYPE SQL_ID        PREV_SQL_ID    BLOCKING_SESSION_STATUS BLOCKING_INSTANCE BLOCKING_SESSION FINAL_BLOCKING_SESSION_STATUS FINAL_BLOCKING_INSTANCE FINAL_BLOCKING_SESSION EVENT
------- ------- --------- ---------------- ---- ------------- -------------- ----------------------- ----------------- ---------------- ----------------------------- ----------------------- ---------------------- ----------------------------
 108    22447   ACTIVE    Gcg log parser 1 USER               fqr8pndc6p36h  VALID                   1                 1581             VALID                         1                       845                    library cache: mutex X
 639    40147   ACTIVE    Gcg log parser 3 USER               fqr8pndc6p36h  VALID                   1                 1581             VALID                         1                       845                    library cache: mutex X
 742    34683   ACTIVE    Gcg log parser 2 USER a16hxxtp5sxyw fqr8pndc6p36h  VALID                   1                 1581             VALID                         1                       845                    library cache: mutex X
于 2012-07-27T14:42:54.007 に答える
2

私も最近この問題に遭遇し、このクエリを使用してOracleでロック/ロックされたセッションを検索しました。

select 
   inst_id||' '||sid||','||serial# inst_sid_s#, 
   username,
   row_wait_obj#||','||row_wait_block#||','||row_wait_row# obj_lck,
   blocking_session_Status||' '||blocking_instance||','||blocking_session blk_info,
   final_blocking_session_Status||' '||final_blocking_instance||','||final_blocking_session f_blk_info,
   event, 
   seconds_in_wait 
from 
   gv$session 
where 
   lockwait is not null
order by 
   inst_id;

出典: http ://www.dba-oracle.com/t_final_blocking_session_final_blocking_instance.htm

于 2016-04-11T04:50:03.877 に答える